builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0135 starttime: 1496355331.05 results: success (0) revision: b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.050122) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.050541) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.050836) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.076283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.076610) ========= 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.nrkImdaf67/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.9i14qheRth/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-01 15:15:31-- 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% 204M=0s 2017-06-01 15:15:31 (204 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130369 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.256290) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.256626) ========= 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.nrkImdaf67/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.9i14qheRth/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.036741 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:31.318506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 15:15:31.319036) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f --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 b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nrkImdaf67/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.9i14qheRth/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-01 15:15:31,423 truncating revision to first 12 chars 2017-06-01 15:15:31,423 Setting DEBUG logging. 2017-06-01 15:15:31,423 attempt 1/10 2017-06-01 15:15:31,423 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b138d2f271fd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 15:15:32,754 unpacking tar archive at: mozilla-central-b138d2f271fd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.606409 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 15:15:32.951807) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:32.952198) ========= script_repo_revision: b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:32.952602) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:32.952960) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 15:15:32.972438) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-01 15:15:32.972828) ========= /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.nrkImdaf67/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.9i14qheRth/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:15:33 INFO - MultiFileLogger online at 20170601 15:15:33 in /builds/slave/test 15:15:33 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 15:15:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:15:33 INFO - {'append_to_log': False, 15:15:33 INFO - 'base_work_dir': '/builds/slave/test', 15:15:33 INFO - 'blob_upload_branch': 'Firefox', 15:15:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:15:33 INFO - 'branch': 'Firefox', 15:15:33 INFO - 'buildbot_json_path': 'buildprops.json', 15:15:33 INFO - 'config_files': ('talos/mac_config.py',), 15:15:33 INFO - 'default_actions': ('clobber', 15:15:33 INFO - 'read-buildbot-config', 15:15:33 INFO - 'download-and-extract', 15:15:33 INFO - 'populate-webroot', 15:15:33 INFO - 'create-virtualenv', 15:15:33 INFO - 'install', 15:15:33 INFO - 'setup-mitmproxy', 15:15:33 INFO - 'run-tests'), 15:15:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:15:33 INFO - 'download_minidump_stackwalk': True, 15:15:33 INFO - 'download_symbols': 'ondemand', 15:15:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:15:33 INFO - 'tooltool.py': '/tools/tooltool.py', 15:15:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:15:33 INFO - '/tools/misc-python/virtualenv.py')}, 15:15:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:15:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:15:33 INFO - 'gecko_profile': False, 15:15:33 INFO - 'gecko_profile_interval': 0, 15:15:33 INFO - 'installer_path': 'installer.exe', 15:15:33 INFO - 'log_level': 'info', 15:15:33 INFO - 'log_name': 'talos', 15:15:33 INFO - 'log_to_console': True, 15:15:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:15:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:15:33 INFO - 'opt_config_files': (), 15:15:33 INFO - 'pip_index': False, 15:15:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:15:33 INFO - 'cmd': ('bash', 15:15:33 INFO - '-c', 15:15:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:15:33 INFO - 'enabled': True, 15:15:33 INFO - 'halt_on_failure': False, 15:15:33 INFO - 'name': 'check_screen_resolution'},), 15:15:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:15:33 INFO - 'cmd': ('bash', 15:15:33 INFO - '-c', 15:15:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:15:33 INFO - 'enabled': True, 15:15:33 INFO - 'halt_on_failure': False, 15:15:33 INFO - 'name': 'check_screen_resolution'},), 15:15:33 INFO - 'run_cmd_checks_enabled': True, 15:15:33 INFO - 'suite': 'dromaeojs-e10s', 15:15:33 INFO - 'system_bits': '32', 15:15:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:15:33 INFO - 'title': 't-yosemite-r7-0135', 15:15:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:15:33 INFO - 'use_talos_json': True, 15:15:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:15:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:15:33 INFO - 'work_dir': 'build'} 15:15:33 INFO - [mozharness: 2017-06-01 22:15:33.190904Z] Running clobber step. 15:15:33 INFO - Running pre-action listener: _resource_record_pre_action 15:15:33 INFO - Running main action method: clobber 15:15:33 INFO - rmtree: /builds/slave/test/build 15:15:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:15:35 INFO - Running post-action listener: _resource_record_post_action 15:15:35 INFO - [mozharness: 2017-06-01 22:15:35.644896Z] Finished clobber step (success) 15:15:35 INFO - [mozharness: 2017-06-01 22:15:35.645046Z] Running read-buildbot-config step. 15:15:35 INFO - Running pre-action listener: _resource_record_pre_action 15:15:35 INFO - Running main action method: read_buildbot_config 15:15:35 INFO - Using buildbot properties: 15:15:35 INFO - { 15:15:35 INFO - "project": "", 15:15:35 INFO - "product": "firefox", 15:15:35 INFO - "who": "ryanvm@gmail.com", 15:15:35 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 15:15:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:15:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 15:15:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:15:35 INFO - "stage_platform": "macosx64", 15:15:35 INFO - "basedir": "/builds/slave/test", 15:15:35 INFO - "buildnumber": 172, 15:15:35 INFO - "platform": "yosemite_r7", 15:15:35 INFO - "parent_task_id": "Z_y6pr3VRSOlrkIDYHqXZQ", 15:15:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:15:35 INFO - "slavebuilddir": "test", 15:15:35 INFO - "taskId": "YKT8p0d_QCqK3coYARxdaQ", 15:15:35 INFO - "branch": "mozilla-central", 15:15:35 INFO - "script_repo_revision": "production", 15:15:35 INFO - "revision": "b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f", 15:15:35 INFO - "slavename": "t-yosemite-r7-0135", 15:15:35 INFO - "repo_path": "mozilla-central" 15:15:35 INFO - } 15:15:35 INFO - Finding installer, test and symbols from parent task. 15:15:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YKT8p0d_QCqK3coYARxdaQ'}, attempt #1 15:15:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YKT8p0d_QCqK3coYARxdaQ'}, attempt #1 15:15:36 INFO - Task dependencies: Eys2x0kNRJitSCr_H0Bi0w Z_y6pr3VRSOlrkIDYHqXZQ 15:15:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ'}, attempt #1 15:15:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:15:37 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/test_packages.json 15:15:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/target.crashreporter-symbols.zip 15:15:37 INFO - Running post-action listener: _resource_record_post_action 15:15:37 INFO - [mozharness: 2017-06-01 22:15:37.430577Z] Finished read-buildbot-config step (success) 15:15:37 INFO - [mozharness: 2017-06-01 22:15:37.430709Z] Running download-and-extract step. 15:15:37 INFO - Running pre-action listener: _resource_record_pre_action 15:15:37 INFO - Running main action method: download_and_extract 15:15:37 INFO - mkdir: /builds/slave/test/build/tests 15:15:37 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')]} 15:15:37 INFO - trying https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:15:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:15:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:15:38 INFO - Downloaded 1325 bytes. 15:15:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:15:38 INFO - Using the following test package requirements: 15:15:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:15:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:15:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:15:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:15:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'jsshell-mac.zip'], 15:15:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:15:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:15:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:15:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:15:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:15:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:15:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:15:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:15:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:15:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:15:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:15:40 INFO - Content-Length response header: 57630697 15:15:40 INFO - Bytes received: 57630697 15:15:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:15:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:15:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:15:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:16:00 INFO - Content-Length response header: 57630697 15:16:00 INFO - Bytes received: 57630697 15:16:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:16:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:16:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:16:18 INFO - Content-Length response header: 14447425 15:16:18 INFO - Bytes received: 14447425 15:16:18 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')]} 15:16:18 INFO - trying https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:16:18 INFO - Downloading https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:16:20 INFO - Downloaded 67914873 bytes. 15:16:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:16:20 INFO - mkdir: /builds/slave/test/properties 15:16:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:16:20 INFO - Writing to file /builds/slave/test/properties/build_url 15:16:20 INFO - Contents: 15:16:20 INFO - build_url:https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:16:20 INFO - Running post-action listener: _resource_record_post_action 15:16:20 INFO - Running post-action listener: set_extra_try_arguments 15:16:20 INFO - [mozharness: 2017-06-01 22:16:20.916403Z] Finished download-and-extract step (success) 15:16:20 INFO - [mozharness: 2017-06-01 22:16:20.916528Z] Running populate-webroot step. 15:16:20 INFO - Running pre-action listener: _resource_record_pre_action 15:16:20 INFO - Running main action method: populate_webroot 15:16:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:16:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:16:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:16:20 INFO - '--tppagecycles', 15:16:20 INFO - '7'], 15:16:20 INFO - 'tests': ['tsvgm']}}, 15:16:20 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:16:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:16:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:16:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['damp', 'tps']}, 15:16:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'tests': ['damp', 'tps']}, 15:16:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['dromaeo_dom']}, 15:16:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:16:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:16:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:16:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:16:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['a11yr', 15:16:20 INFO - 'ts_paint', 15:16:20 INFO - 'tpaint', 15:16:20 INFO - 'sessionrestore', 15:16:20 INFO - 'sessionrestore_no_auto_restore', 15:16:20 INFO - 'tabpaint']}, 15:16:20 INFO - 'other-e10s': {'tests': ['a11yr', 15:16:20 INFO - 'ts_paint', 15:16:20 INFO - 'tpaint', 15:16:20 INFO - 'sessionrestore', 15:16:20 INFO - 'sessionrestore_no_auto_restore', 15:16:20 INFO - 'tabpaint']}, 15:16:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:16:20 INFO - 'ts_paint', 15:16:20 INFO - 'tpaint', 15:16:20 INFO - 'sessionrestore', 15:16:20 INFO - 'sessionrestore_no_auto_restore', 15:16:20 INFO - 'tabpaint']}, 15:16:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:16:20 INFO - 'ts_paint', 15:16:20 INFO - 'tpaint', 15:16:20 INFO - 'sessionrestore', 15:16:20 INFO - 'sessionrestore_no_auto_restore', 15:16:20 INFO - 'tabpaint']}, 15:16:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['a11yr', 15:16:20 INFO - 'ts_paint', 15:16:20 INFO - 'tpaint', 15:16:20 INFO - 'sessionrestore', 15:16:20 INFO - 'sessionrestore_no_auto_restore', 15:16:20 INFO - 'tabpaint']}, 15:16:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['a11yr', 15:16:20 INFO - 'ts_paint', 15:16:20 INFO - 'tpaint', 15:16:20 INFO - 'sessionrestore', 15:16:20 INFO - 'sessionrestore_no_auto_restore', 15:16:20 INFO - 'tabpaint']}, 15:16:20 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:16:20 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:16:20 INFO - 'bloom_basic_ref']}, 15:16:20 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 15:16:20 INFO - 'talos_options': ['--mitmproxy', 15:16:20 INFO - 'mitmproxy-recording-1.mp', 15:16:20 INFO - '--firstNonBlankPaint'], 15:16:20 INFO - 'tests': ['Quantum_1']}, 15:16:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['tsvgx', 15:16:20 INFO - 'tsvgr_opacity', 15:16:20 INFO - 'tart', 15:16:20 INFO - 'tscrollx', 15:16:20 INFO - 'cart', 15:16:20 INFO - 'tsvg_static']}, 15:16:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:16:20 INFO - 'tsvgr_opacity', 15:16:20 INFO - 'tart', 15:16:20 INFO - 'tscrollx', 15:16:20 INFO - 'cart', 15:16:20 INFO - 'tsvg_static']}, 15:16:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'talos_options': ['--disable-e10s'], 15:16:20 INFO - 'tests': ['tp5o']}, 15:16:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:16:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'talos_options': ['--disable-e10s', 15:16:20 INFO - '--xperf_path', 15:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:20 INFO - 'tests': ['tp5n']}, 15:16:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:16:20 INFO - 'talos_options': ['--xperf_path', 15:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:20 INFO - 'tests': ['tp5n']}}, 15:16:20 INFO - 'talos.zip': {'path': '', 15:16:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:16:20 INFO - Running post-action listener: _resource_record_post_action 15:16:20 INFO - [mozharness: 2017-06-01 22:16:20.938685Z] Finished populate-webroot step (success) 15:16:20 INFO - [mozharness: 2017-06-01 22:16:20.938825Z] Running create-virtualenv step. 15:16:20 INFO - Running pre-action listener: _resource_record_pre_action 15:16:20 INFO - Running main action method: create_virtualenv 15:16:20 INFO - Creating virtualenv /builds/slave/test/build/venv 15:16:20 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 15:16:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:16:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:16:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:16:21 INFO - Using real prefix '/tools/python27' 15:16:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 15:16:24 INFO - Installing pip.................done. 15:16:24 INFO - Return code: 0 15:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:16:25 INFO - Reading from file tmpfile_stdout 15:16:25 INFO - Output received: 15:16:25 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) 15:16:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:16:25 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')]} 15:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:25 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')]} 15:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:26 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 15:16:26 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 15:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:26 INFO - 'HOME': '/Users/cltbld', 15:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:26 INFO - 'LOGNAME': 'cltbld', 15:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:26 INFO - 'MOZ_NO_REMOTE': '1', 15:16:26 INFO - 'NO_EM_RESTART': '1', 15:16:26 INFO - 'PAGER': '/bin/cat', 15:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:26 INFO - 'PWD': '/builds/slave/test', 15:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:26 INFO - 'SHELL': '/bin/bash', 15:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:26 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:26 INFO - 'USER': 'cltbld', 15:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:26 INFO - 'XPC_FLAGS': '0x0', 15:16:26 INFO - 'XPC_SERVICE_NAME': '0', 15:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:26 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 15:16:26 INFO - Cleaning up... 15:16:26 INFO - Return code: 0 15:16:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:16:26 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')]} 15:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:26 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')]} 15:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:26 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 15:16:26 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 15:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:26 INFO - 'HOME': '/Users/cltbld', 15:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:26 INFO - 'LOGNAME': 'cltbld', 15:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:26 INFO - 'MOZ_NO_REMOTE': '1', 15:16:26 INFO - 'NO_EM_RESTART': '1', 15:16:26 INFO - 'PAGER': '/bin/cat', 15:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:26 INFO - 'PWD': '/builds/slave/test', 15:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:26 INFO - 'SHELL': '/bin/bash', 15:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:26 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:26 INFO - 'USER': 'cltbld', 15:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:26 INFO - 'XPC_FLAGS': '0x0', 15:16:26 INFO - 'XPC_SERVICE_NAME': '0', 15:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:27 INFO - Downloading/unpacking psutil>=3.1.1 15:16:27 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 15:16:27 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 15:16:27 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 15:16:27 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 15:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:29 INFO - Installing collected packages: psutil 15:16:29 INFO - Running setup.py install for psutil 15:16:29 INFO - building 'psutil._psutil_osx' extension 15:16:29 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 15:16:30 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 15:16:30 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 15:16:30 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 15:16:30 INFO - building 'psutil._psutil_posix' extension 15:16:30 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 15:16:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:16:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:16:30 INFO - ^ 15:16:30 INFO - 1 warning generated. 15:16:30 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 15:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:30 INFO - Successfully installed psutil 15:16:30 INFO - Cleaning up... 15:16:30 INFO - Return code: 0 15:16:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:16:30 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')]} 15:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:30 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')]} 15:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:30 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 15:16:30 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 15:16:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:30 INFO - 'HOME': '/Users/cltbld', 15:16:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:30 INFO - 'LOGNAME': 'cltbld', 15:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:30 INFO - 'MOZ_NO_REMOTE': '1', 15:16:30 INFO - 'NO_EM_RESTART': '1', 15:16:30 INFO - 'PAGER': '/bin/cat', 15:16:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:30 INFO - 'PWD': '/builds/slave/test', 15:16:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:30 INFO - 'SHELL': '/bin/bash', 15:16:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:30 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:30 INFO - 'USER': 'cltbld', 15:16:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:30 INFO - 'XPC_FLAGS': '0x0', 15:16:30 INFO - 'XPC_SERVICE_NAME': '0', 15:16:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:16:30 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 15:16:30 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 15:16:30 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 15:16:30 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 15:16:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:16:32 INFO - Installing collected packages: mozsystemmonitor 15:16:32 INFO - Running setup.py install for mozsystemmonitor 15:16:32 INFO - Successfully installed mozsystemmonitor 15:16:32 INFO - Cleaning up... 15:16:32 INFO - Return code: 0 15:16:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:16:32 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')]} 15:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:32 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')]} 15:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:32 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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:32 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 15:16:32 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 15:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:32 INFO - 'HOME': '/Users/cltbld', 15:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:32 INFO - 'LOGNAME': 'cltbld', 15:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:32 INFO - 'MOZ_NO_REMOTE': '1', 15:16:32 INFO - 'NO_EM_RESTART': '1', 15:16:32 INFO - 'PAGER': '/bin/cat', 15:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:32 INFO - 'PWD': '/builds/slave/test', 15:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:32 INFO - 'SHELL': '/bin/bash', 15:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:32 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:32 INFO - 'USER': 'cltbld', 15:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:32 INFO - 'XPC_FLAGS': '0x0', 15:16:32 INFO - 'XPC_SERVICE_NAME': '0', 15:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:32 INFO - Downloading/unpacking jsonschema==2.5.1 15:16:32 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 15:16:32 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 15:16:32 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 15:16:32 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 15:16:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:16:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:34 INFO - Downloading functools32-3.2.3-2.tar.gz 15:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:16:34 INFO - warning: no files found matching '*.txt' 15:16:34 INFO - no previously-included directories found matching 'build' 15:16:34 INFO - no previously-included directories found matching 'dist' 15:16:34 INFO - no previously-included directories found matching '.git*' 15:16:34 INFO - Installing collected packages: jsonschema, functools32 15:16:34 INFO - Running setup.py install for functools32 15:16:34 INFO - warning: no files found matching '*.txt' 15:16:34 INFO - no previously-included directories found matching 'build' 15:16:34 INFO - no previously-included directories found matching 'dist' 15:16:34 INFO - no previously-included directories found matching '.git*' 15:16:34 INFO - Successfully installed jsonschema functools32 15:16:34 INFO - Cleaning up... 15:16:34 INFO - Return code: 0 15:16:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:16:34 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')]} 15:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:34 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')]} 15:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:34 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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:34 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 15:16:34 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 15:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:34 INFO - 'HOME': '/Users/cltbld', 15:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:34 INFO - 'LOGNAME': 'cltbld', 15:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:34 INFO - 'MOZ_NO_REMOTE': '1', 15:16:34 INFO - 'NO_EM_RESTART': '1', 15:16:34 INFO - 'PAGER': '/bin/cat', 15:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:34 INFO - 'PWD': '/builds/slave/test', 15:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:34 INFO - 'SHELL': '/bin/bash', 15:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:34 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:34 INFO - 'USER': 'cltbld', 15:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:34 INFO - 'XPC_FLAGS': '0x0', 15:16:34 INFO - 'XPC_SERVICE_NAME': '0', 15:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:16:35 INFO - Cleaning up... 15:16:35 INFO - Return code: 0 15:16:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:16:35 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')]} 15:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:35 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')]} 15:16:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:35 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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:35 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 15:16:35 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 15:16:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:35 INFO - 'HOME': '/Users/cltbld', 15:16:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:35 INFO - 'LOGNAME': 'cltbld', 15:16:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:35 INFO - 'MOZ_NO_REMOTE': '1', 15:16:35 INFO - 'NO_EM_RESTART': '1', 15:16:35 INFO - 'PAGER': '/bin/cat', 15:16:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:35 INFO - 'PWD': '/builds/slave/test', 15:16:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:35 INFO - 'SHELL': '/bin/bash', 15:16:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:35 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:35 INFO - 'USER': 'cltbld', 15:16:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:35 INFO - 'XPC_FLAGS': '0x0', 15:16:35 INFO - 'XPC_SERVICE_NAME': '0', 15:16:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:35 INFO - Downloading/unpacking blobuploader==1.2.4 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:36 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:36 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 15:16:36 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 15:16:36 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 15:16:36 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 15:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:37 INFO - Downloading docopt-0.6.1.tar.gz 15:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:37 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:37 INFO - Running setup.py install for blobuploader 15:16:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:37 INFO - Running setup.py install for requests 15:16:38 INFO - Running setup.py install for docopt 15:16:38 INFO - Successfully installed blobuploader requests docopt 15:16:38 INFO - Cleaning up... 15:16:38 INFO - Return code: 0 15:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:38 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')]} 15:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:38 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')]} 15:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:38 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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:38 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 15:16:38 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 15:16:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:38 INFO - 'HOME': '/Users/cltbld', 15:16:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:38 INFO - 'LOGNAME': 'cltbld', 15:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:38 INFO - 'MOZ_NO_REMOTE': '1', 15:16:38 INFO - 'NO_EM_RESTART': '1', 15:16:38 INFO - 'PAGER': '/bin/cat', 15:16:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:38 INFO - 'PWD': '/builds/slave/test', 15:16:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:38 INFO - 'SHELL': '/bin/bash', 15:16:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:38 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:38 INFO - 'USER': 'cltbld', 15:16:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:38 INFO - 'XPC_FLAGS': '0x0', 15:16:38 INFO - 'XPC_SERVICE_NAME': '0', 15:16:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:38 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-GuhEbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-qlLTlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ISAWIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-CB7_RP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-xdl0vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-swR5U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-_GfBp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-7QIy92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-jcwOh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-PZM2SI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:39 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-C1VvjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:40 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-vB7ZDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:40 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-APrrv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:40 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MHDU7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:40 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-azYQrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:40 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-4R2Yaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:40 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-l0fjUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:40 INFO - Running setup.py install for manifestparser 15:16:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:40 INFO - Running setup.py install for mozcrash 15:16:40 INFO - Running setup.py install for mozdebug 15:16:41 INFO - Running setup.py install for mozdevice 15:16:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Running setup.py install for mozfile 15:16:41 INFO - Running setup.py install for mozhttpd 15:16:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Running setup.py install for mozinfo 15:16:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Running setup.py install for mozInstall 15:16:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:41 INFO - Running setup.py install for mozleak 15:16:41 INFO - Running setup.py install for mozlog 15:16:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Running setup.py install for moznetwork 15:16:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Running setup.py install for mozprocess 15:16:42 INFO - Running setup.py install for mozprofile 15:16:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Running setup.py install for mozrunner 15:16:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:42 INFO - Running setup.py install for mozscreenshot 15:16:42 INFO - Running setup.py install for moztest 15:16:43 INFO - Running setup.py install for mozversion 15:16:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:43 INFO - Cleaning up... 15:16:43 INFO - Return code: 0 15:16:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:43 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')]} 15:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:43 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')]} 15:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:43 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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:43 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 15:16:43 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 15:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:43 INFO - 'HOME': '/Users/cltbld', 15:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:43 INFO - 'LOGNAME': 'cltbld', 15:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:43 INFO - 'MOZ_NO_REMOTE': '1', 15:16:43 INFO - 'NO_EM_RESTART': '1', 15:16:43 INFO - 'PAGER': '/bin/cat', 15:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:43 INFO - 'PWD': '/builds/slave/test', 15:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:43 INFO - 'SHELL': '/bin/bash', 15:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:43 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:43 INFO - 'USER': 'cltbld', 15:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:43 INFO - 'XPC_FLAGS': '0x0', 15:16:43 INFO - 'XPC_SERVICE_NAME': '0', 15:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-hhGCHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:43 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)) 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ijt0QT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:43 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)) 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-kerCMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:43 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)) 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Lo5dPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:43 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)) 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MzqVjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-YJ8uTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Aim2fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-He5OmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-XxATuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-QiZqKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-E0dF0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-RRv4s2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-6Jccij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Cdcz_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:44 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)) 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-26IbpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-bblqfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:45 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)) 15:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-H_F4Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:45 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)) 15:16:45 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)) 15:16:45 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)) 15:16:45 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)) 15:16:45 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)) 15:16:45 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)) 15:16:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:45 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 15:16:45 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 15:16:45 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 15:16:45 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 15:16:46 INFO - Downloading blessings-1.6.tar.gz 15:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:46 INFO - Installing collected packages: blessings 15:16:46 INFO - Running setup.py install for blessings 15:16:46 INFO - Successfully installed blessings 15:16:46 INFO - Cleaning up... 15:16:47 INFO - Return code: 0 15:16:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:47 INFO - Reading from file tmpfile_stdout 15:16:47 INFO - Current package versions: 15:16:47 INFO - blessings == 1.6 15:16:47 INFO - blobuploader == 1.2.4 15:16:47 INFO - docopt == 0.6.1 15:16:47 INFO - functools32 == 3.2.3-2 15:16:47 INFO - jsonschema == 2.5.1 15:16:47 INFO - manifestparser == 1.1 15:16:47 INFO - mozInstall == 1.12 15:16:47 INFO - mozcrash == 1.0 15:16:47 INFO - mozdebug == 0.1 15:16:47 INFO - mozdevice == 0.51 15:16:47 INFO - mozfile == 1.2 15:16:47 INFO - mozhttpd == 0.7 15:16:47 INFO - mozinfo == 0.9 15:16:47 INFO - mozleak == 0.1 15:16:47 INFO - mozlog == 3.4 15:16:47 INFO - moznetwork == 0.27 15:16:47 INFO - mozprocess == 0.25 15:16:47 INFO - mozprofile == 0.28 15:16:47 INFO - mozrunner == 6.13 15:16:47 INFO - mozscreenshot == 0.1 15:16:47 INFO - mozsystemmonitor == 0.3 15:16:47 INFO - moztest == 0.8 15:16:47 INFO - mozversion == 1.4 15:16:47 INFO - psutil == 3.1.1 15:16:47 INFO - requests == 1.2.3 15:16:47 INFO - wsgiref == 0.1.2 15:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:47 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')]} 15:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:47 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')]} 15:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:47 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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:47 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 15:16:47 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 15:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:47 INFO - 'HOME': '/Users/cltbld', 15:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:47 INFO - 'LOGNAME': 'cltbld', 15:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:47 INFO - 'MOZ_NO_REMOTE': '1', 15:16:47 INFO - 'NO_EM_RESTART': '1', 15:16:47 INFO - 'PAGER': '/bin/cat', 15:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:47 INFO - 'PWD': '/builds/slave/test', 15:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:47 INFO - 'SHELL': '/bin/bash', 15:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:47 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:47 INFO - 'USER': 'cltbld', 15:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:47 INFO - 'XPC_FLAGS': '0x0', 15:16:47 INFO - 'XPC_SERVICE_NAME': '0', 15:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 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)) 15:16:47 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:16:47 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 15:16:47 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 15:16:47 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 15:16:47 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 15:16:48 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:16:49 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)) 15:16:49 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)) 15:16:49 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)) 15:16:49 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)) 15:16:49 INFO - Installing collected packages: marionette-driver 15:16:49 INFO - Running setup.py install for marionette-driver 15:16:49 INFO - Successfully installed marionette-driver 15:16:49 INFO - Cleaning up... 15:16:49 INFO - Return code: 0 15:16:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16:49 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')]} 15:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:49 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')]} 15:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x103470c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103474768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8bb554100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, '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.nrkImdaf67/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.9i14qheRth/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 15:16:49 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 15:16:49 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 15:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:49 INFO - 'HOME': '/Users/cltbld', 15:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:49 INFO - 'LOGNAME': 'cltbld', 15:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:49 INFO - 'MOZ_NO_REMOTE': '1', 15:16:49 INFO - 'NO_EM_RESTART': '1', 15:16:49 INFO - 'PAGER': '/bin/cat', 15:16:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:49 INFO - 'PWD': '/builds/slave/test', 15:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:49 INFO - 'SHELL': '/bin/bash', 15:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:16:49 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:49 INFO - 'USER': 'cltbld', 15:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:49 INFO - 'XPC_FLAGS': '0x0', 15:16:49 INFO - 'XPC_SERVICE_NAME': '0', 15:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:49 INFO - Cleaning up... 15:16:49 INFO - Return code: 0 15:16:49 INFO - Running post-action listener: _resource_record_post_action 15:16:49 INFO - Running post-action listener: _start_resource_monitoring 15:16:49 INFO - Starting resource monitoring. 15:16:49 INFO - [mozharness: 2017-06-01 22:16:49.566036Z] Finished create-virtualenv step (success) 15:16:49 INFO - [mozharness: 2017-06-01 22:16:49.567073Z] Running install step. 15:16:49 INFO - Running pre-action listener: _resource_record_pre_action 15:16:49 INFO - Running main action method: install 15:16:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:49 INFO - Reading from file tmpfile_stdout 15:16:49 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:49 INFO - Reading from file tmpfile_stdout 15:16:49 INFO - Output received: 15:16:49 INFO - Usage: mozinstall [options] installer 15:16:49 INFO - Options: 15:16:49 INFO - -h, --help show this help message and exit 15:16:49 INFO - -d DEST, --destination=DEST 15:16:49 INFO - Directory to install application into. [default: 15:16:49 INFO - "/builds/slave/test"] 15:16:49 INFO - --app=APP Application being installed. [default: firefox] 15:16:49 INFO - mkdir: /builds/slave/test/build/application 15:16:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:17:10 INFO - Reading from file tmpfile_stdout 15:17:10 INFO - Output received: 15:17:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:17:10 INFO - Running post-action listener: _resource_record_post_action 15:17:10 INFO - [mozharness: 2017-06-01 22:17:10.979442Z] Finished install step (success) 15:17:10 INFO - [mozharness: 2017-06-01 22:17:10.979572Z] Running setup-mitmproxy step. 15:17:10 INFO - Running pre-action listener: _resource_record_pre_action 15:17:10 INFO - Running main action method: setup_mitmproxy 15:17:10 INFO - Skipping: mitmproxy is not required 15:17:10 INFO - Running post-action listener: _resource_record_post_action 15:17:10 INFO - [mozharness: 2017-06-01 22:17:10.980214Z] Finished setup-mitmproxy step (success) 15:17:10 INFO - [mozharness: 2017-06-01 22:17:10.980332Z] Running run-tests step. 15:17:10 INFO - Running pre-action listener: _resource_record_pre_action 15:17:10 INFO - Running main action method: run_tests 15:17:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:17:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:17:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:17:10 INFO - 2017-06-01 15:17:10.994 screenresolution[1626:7340] starting screenresolution argv=screenresolution get 15:17:10 INFO - 2017-06-01 15:17:10.997 screenresolution[1626:7340] Display 0: 1600x1200x32@60 15:17:11 INFO - 2017-06-01 15:17:11.003 screenresolution[1627:7343] starting screenresolution argv=screenresolution list 15:17:11 INFO - Available Modes on Display 0 15:17:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:17:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:17:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:17:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:17:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:17:11 INFO - Intel Iris: 15:17:11 INFO - Chipset Model: Intel Iris 15:17:11 INFO - Type: GPU 15:17:11 INFO - Bus: Built-In 15:17:11 INFO - VRAM (Dynamic, Max): 1536 MB 15:17:11 INFO - Vendor: Intel (0x8086) 15:17:11 INFO - Device ID: 0x0a2e 15:17:11 INFO - Revision ID: 0x0009 15:17:11 INFO - Displays: 15:17:11 INFO - Display: 15:17:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:17:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:17:11 INFO - Display Serial Number: 1600x1200 60 15:17:11 INFO - Main Display: Yes 15:17:11 INFO - Mirror: Off 15:17:11 INFO - Online: Yes 15:17:11 INFO - Rotation: Supported 15:17:11 INFO - Return code: 0 15:17:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:11 INFO - Python 2.7.3 15:17:11 INFO - Return code: 0 15:17:11 INFO - grabbing minidump binary from tooltool 15:17:11 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')]} 15:17:11 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 0x1033f9c60>, '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 0x10318ecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031b7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17:11 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 15:17:11 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 15:17:11 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 15:17:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:11 INFO - Return code: 0 15:17:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:17:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:11 INFO - ENV: RUST_BACKTRACE is now 1 15:17:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:17:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:11 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/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 15:17:11 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/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0135 --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 15:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nrkImdaf67/Render', 15:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:11 INFO - 'HOME': '/Users/cltbld', 15:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:11 INFO - 'LOGNAME': 'cltbld', 15:17:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:11 INFO - 'MOZ_NO_REMOTE': '1', 15:17:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:11 INFO - 'NO_EM_RESTART': '1', 15:17:11 INFO - 'PAGER': '/bin/cat', 15:17:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:11 INFO - 'PWD': '/builds/slave/test', 15:17:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:11 INFO - 'RUST_BACKTRACE': '1', 15:17:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:17:11 INFO - 'SHELL': '/bin/bash', 15:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9i14qheRth/Listeners', 15:17:11 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 15:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:11 INFO - 'USER': 'cltbld', 15:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:11 INFO - 'XPC_FLAGS': '0x0', 15:17:11 INFO - 'XPC_SERVICE_NAME': '0', 15:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:11 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/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 15:17:11 INFO - mozversion application_buildid: 20170601134707 15:17:11 INFO - mozversion application_changeset: b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f 15:17:11 INFO - mozversion application_display_name: Nightly 15:17:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:11 INFO - mozversion application_name: Firefox 15:17:11 INFO - mozversion application_remotingname: firefox 15:17:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:17:11 INFO - mozversion application_vendor: Mozilla 15:17:11 INFO - mozversion application_version: 55.0a1 15:17:11 INFO - mozversion platform_buildid: 20170601134707 15:17:11 INFO - mozversion platform_changeset: b138d2f271fdb598bf8a66c2dcb7fe391ca2a96f 15:17:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:17:11 INFO - mozversion platform_version: 55.0a1 15:17:11 INFO - using testdate: 1496355431 15:17:11 INFO - actual date: 1496355431 15:17:11 INFO - starting webserver on 'localhost:49231' 15:17:11 INFO - SUITE-START | Running 2 tests 15:17:11 INFO - TEST-START | dromaeo_css 15:17:11 INFO - operating with platform_type : mac_ 15:17:11 INFO - Initialising browser for dromaeo_css test... 15:17:11 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpTrJXVa/profile 15:17:11 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 15:17:45 INFO - TEST-INFO | 1637: killed by SIGTERM 15:17:45 INFO - Browser initialized. 15:17:45 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:45 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpTrJXVa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:47 INFO - PID 1647 | 2017-06-01 15:17:47.677 plugin-container[1648:7892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a3b, name = 'com.apple.tsm.portname' 15:17:47 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:47 INFO - PID 1647 | 2017-06-01 15:17:47.678 plugin-container[1648:7892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 15:17:47 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:47 INFO - PID 1647 | 2017-06-01 15:17:47.678 plugin-container[1648:7892] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:48 INFO - PID 1647 | 2017-06-01 15:17:48.179 plugin-container[1649:7938] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 15:17:48 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:48 INFO - PID 1647 | 2017-06-01 15:17:48.180 plugin-container[1649:7938] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 15:17:48 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:48 INFO - PID 1647 | 2017-06-01 15:17:48.180 plugin-container[1649:7938] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:57 INFO - PID 1647 | RSS: Main: 268537856 15:17:57 INFO - PID 1647 | 15:20:59 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49231/tests/dromaeo/cssquery-ext.html) 15:21:01 INFO - PID 1647 | RSS: Main: 267948032 15:21:01 INFO - PID 1647 | 15:24:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-ext.html (next: http://localhost:49231/tests/dromaeo/cssquery-jquery.html) 15:24:03 INFO - PID 1647 | RSS: Main: 283348992 15:24:03 INFO - PID 1647 | 15:27:05 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49231/tests/dromaeo/cssquery-mootools.html) 15:27:06 INFO - PID 1647 | RSS: Main: 282202112 15:27:06 INFO - PID 1647 | 15:30:08 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49231/tests/dromaeo/cssquery-prototype.html) 15:30:08 INFO - PID 1647 | RSS: Main: 289099776 15:30:08 INFO - PID 1647 | 15:33:11 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49231/tests/dromaeo/cssquery-yui.html) 15:33:12 INFO - PID 1647 | RSS: Main: 289120256 15:33:12 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49231/tests/dromaeo/cssquery-yui.html (next: http://localhost:49231/tests/dromaeo/cssquery-dojo.html) 15:36:14 INFO - PID 1647 | RSS: Main: 289062912 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | __start_tp_report 15:36:14 INFO - PID 1647 | _x_x_mozilla_page_load 15:36:14 INFO - PID 1647 | _x_x_mozilla_page_load_details 15:36:14 INFO - PID 1647 | |i|pagename|runs| 15:36:14 INFO - PID 1647 | |0;dojo.html;3591.4074177760654;3598.6041535431104;3629.618890016549;3635.1457407509247;3643.8360273787675;4644;6150.600210986283;6174;6207.410296021871;6242.344553821851;4889.046635905991;6186.257649082105;6200.10098535712;6214.254289485257;6272.1219029335925;4772.665913386073;5273.709945952971;5470.835874923759;5478.479544443291;5535.086710692731;5537.031019571579;5580.720963951807;5602.943970560283;5635;5674.063779476382;4808.903821923577;5301.151815709506;5463.235147079412;5504.697241651707;5519.69641669708;5539.889202215933;5550.27846379972;5576.498115169654;5582.302212223472;5634.014047541664;5498.862528436781;5500.532454741352;5511.283533140666;5526.50261476465;5538.9169162462595;6169.598976066542;6191.133241346215;6237.937620623781;6255.593386429913;6338.746450141989;5569.359523654791;6738.629375384401;6759.797206083826;6761.29006454325;6763.526553141282;5417;6904.344087311748;6908.3437073477935;6912.343327383941;6922.480813938974;6225.875482490324;6272.1219029335925;6349.079383489415;6384.201974753156;6389.808305750835;6940.583564986117;6998.125234345707;7030;7036.9296307036275;7061.152661680631;5483.204935284361;6044.697765111727;6248.687565621701;6462.741490340428;6675.065490831287;6729.730810767613;6765.492588055817;6857.588544687235;6880.793576192722;6895.655217239118;6724.327567243335;6753.628550429774;6759.5606285591275;6775.1531058617675;6778.627175505395;6557.737690492422;6645.401913827779;6690.5316627836555;6770.492213084039;6785.626790526569;5421.4578542146255;5424.077906755861;5427.511523962862;5428.0500912340185;5460.645058071213;4821.855344339676;4828.95171048285;4835.3714017178245;4840.854774356775;4857.80568777252;6130.122981477994;6261.092141639528;6288.339724328971;6307.085472606446;6316.368363163647;6887.965560172267;6893.896591551131;6909.689063992032;6929.237783843773;6956.373926346653;6298.685065746694;6358.586691865014;6364.77723279683;6378.170837791057;6394.904076438857;5898.557608179318;5967.791127310524;6033.698315084228;6119.388061193845;6124.295705993868;6945.444364450731;7039.331263529956;7053.329933656294;7059;7060.976158457098;6842.965785171242;6888.724451021903;6894.172699276119;6927.376536111774;6932.168139823253;5847.8245652630485;6830.863382732218;6968.6864091115;7018.859622807618;7033.542819716702;6676.9666151668935;6724.93275067243;6775.830604234933;6784.0163176338165;6824.454043676395;6875.656217189221;6880;6883.793486195422;6887.931120688729;6931.826704332432;6456.289808121009;6543.116679248241;6549.901751473827;6589.80230593083;6703.430208432338;6505.024246362894;6506.804795856132;6594.076829244005;6604.075429439787;6635.0379195015485;6449.419552240321;6527.412532872065;6530.575512591667;6546.985217291328;6567;6609.966950165219;6693.364130407604;6693.598384096969;6696.397324240646;6704.195496540447;6308.116863639002;6389.2652344980925;6451.774187903401;6509.804705858832;6520.706568204348 15:36:14 INFO - PID 1647 | |1;ext.html;680.6902859199064;784.4626430894838;838.8573942429788;1256.3843716578874;1299.3113649765628;33224.66775332246;39669.60330396695;40203.1959360813;40240.195196096094;40332.59667403325;40516.59483405165;40928.181436371255;40993;41063.384049239285;41073.7946310268;7839.255270749284;7999.760007199764;8220.260176584106;8468.195521425485;8496.617652205665;8349.833003339929;8367;8435.873461898076;8463.4075614707;8464.1112683168;8428.199321064489;8431.536265505394;8467;8488.872666910001;8504.829903401927;8446.873296900552;8447.57762111898;8451.873221901646;8469.576521173916;8498.83002339956;8140.75577732669;8197.180281971756;8269.173082691743;8275.379346548973;8279.95860020696;37704.81147594272;37704.86885393443;37942.43086353707;37963.86108416752;38029.42955855664;3541.1501239702543;3580.104973756561;3581.767185132958;3581.964180358189;3584.480250363709;30674.38651226963;30908.53637195444;30928.536071958937;30938.53592196119;31019.84490077535;3041.3461105862125;3082.645495767993;3084.228942764309;3086.7993580417424;3096.0866544369464;225665.99999999837;245295;248967.75516122484;271879;292090;48739;48747.75626121847;48819.267710984364;48970.51029489731;48990.020199595805;50003;50215.49784502181;50534.49465505298;50562;50613;14372.209528475825;14496.927515362566;14513.709725805636;14539.563813085624;14542.981991260722;11704.47329870158;11800.291982481078;11844.644660660195;11880.287182769061;11897.88102118985;13911.095778774346;14016.719665606835;14028.579142625533;14106.435742570182;14157.292135393394;4828.03439312139;5667.4615911488345;6145.678679084019;6206.162168107338;6275.843103922347;986.5166068626321;1016.192127258831;1041.6927006533092;1070.6092276319243;1074.7420619051372;23585.882070589538;24832.50334993326;24833;24856.254312370656;25062.874685626455;1023.9129673977646;1061.6125114333213;1074.1460538871615;1130.141092769501;1156.8785277545906;12593.181443206162;12716.936415318049;12758.425870835834;12780.169288996185;12787;326884;328070;328543;329049;329638;54376.45623543793;54563.45436545663;54680.45319546833;54739.90520189574;54771.726141369036;1485.2499487758612;1500.9099454032794;1524.1693277163972;1531.1502116325323;1540.9075455472707;24349.87825060863;24629.753702462745;24663;24703.505929880943;24726.13458528943;38169.85490435291;50606;52149.21776173285;52383.73808130935;52419.42741717755;291937;294013.529932349;295560;296059;296486;34216;36770.816145919096;37012.81493592515;37067.62932370642;37085.443718343704;44429;44432;46020.539794601624;46601.834954125065;46796.29805552851;29683.406331873673;30144.84927575348;31562.842185788923;31841.681583183872;32145.839270803495;25008;31049.68950310468;31283.687163128077;31926.840365798023;31989;37522.87431377063;38164.809175953946;38235.85292441231;38271;38332.27504762237;30435.847820760755;30678.846605766827;31707.841460792548;31968.88108916177;32366;23728.52542949166;24726.50546989014;24956;25043;25436.364090897874 15:36:14 INFO - PID 1647 | |2;jquery.html;354.14650691832674;373.21252157946725;383.41720584711237;388.50150102852643;446.3215911814043;14686.412543498274;15347.769783453257;22355.776442235572;22922.312330630088;23110.960006799713;22292.442688932744;22954.770452295474;23269.650955235644;23270.95280712368;23362.766372336315;5538.252335934658;6177.9382206177925;6357.141785858896;6363.4272915437605;6371.713272902707;3597.748157628967;3815.5802861685193;6225.128482012522;6317.842053948642;6327.715252813635;5901.173835662989;6158.476529494998;6185.969070154643;6198.721057552421;6205.565610407273;6148.446639802417;6185.907211391833;6197.628142311476;6199.752009919598;6205.844853878667;5548.0845660465975;5550.86122846928;5553.944460555384;5562.916556251619;5564.081926482166;27606.309842253904;27848.303792405353;28195.295117622223;28521.85912563495;28614;2186.9343919682433;2223.055201539339;2226.8552544084746;2227.376334626307;2234.430220293831;18064.548386290317;18388.448346549623;18590.814091859043;18600.627987440177;18616.162272697762;2029.8477614178996;2049.815516603513;2091.937241882746;2104.4006555018295;2131.7335333083365;167063.16468417703;178619;187105;192824;194531;12324.81512777309;12535.373231338397;12710.491580336682;12889.162204456681;12899.419526121346;16437.83562164387;16448.588785280463;16466.42367517155;16490.83509164893;16496.17519124039;8969.058248883774;9108.590113444778;9108.954455227682;9148.588313525906;9204.079592040742;7289.161746399232;8704.564771761387;8709.042005379402;8752.518611476302;8754.606042728092;6728.495362847806;7036.296370362923;7140.285971402922;7505.324520793155;7702.460827742004;1995.712765266424;2116.8835714035877;2255.5150642611916;2258.491839336169;2293.9541209175723;841.7643059943226;875.4922145155795;933.2114363362947;972.5429048347407;1012.427978192319;20823.79176208249;21310.78689213119;21316.893415532824;21404.678929816066;21673.783262167177;1008.0977525114981;1018.5930497172848;1027.5530144387214;1042.3693665332517;1047.7171163785736;7646.885296720554;8769.429987050822;9205.907940920504;9661.516924153764;10144.695659130239;535699;540866;549569;549999.7500037503;550812;107454;141040;141221;141765;141952.580474196;1165.4813607944552;1187.1511869013696;1207.957721479744;1219.1039585904352;1224.4428784902848;18106.54733631617;18450.446486605422;18683.626327473645;19060;19197.520061998563;64884.35115648972;64910.67544662247;65523;65776.67111664411;66408.6679566599;66902.31683782283;86744;87607;87766;87767.56116219379;10023.847257565474;10109.848352274576;10181.694549163536;10249.12882405004;10256.179505639382;12506.374681266083;17914.46256612304;17950.19224134891;18105.547361316072;18121.184546695167;14125;14602.561923142322;14622.853771462575;14725.779113313094;14748.483803066842;12733.490660373482;12983.870161298266;13029.348532573524;13173.077884547989;13341.533046343331;14425.711485770436;14568.781468278195;14740.336684849422;14874.776878346616;14908.403663853325;10028.799424011333;10040.146587539848;10133.18934485254;10136.949315253376;10150.340227885164;14556.708865822413;14649;14734.484293049692;14800.111993280007;14802 15:36:14 INFO - PID 1647 | |3;mootools.html;1359.1029920252633;1421.133108803629;1429.0996672096585;1438.798603166356;1450.282110355374;11446.255993360446;11603.825942610867;12172.208806427587;12204;12217.755644887096;11947.805219478048;12146.210496317733;12155.696107597292;12175.51297948081;12177.33024683642;2511.7739403453684;2561.6157576363603;2568.319395360231;2570.421655127591;2574.562324404846;2106.357560943919;2118.2691971269965;2199.604071267171;2466.371075375777;2523.4448421347274;2538.530371881199;2558.616207568861;2559.7056338521124;2561.718210996789;2564.205096420106;2162.5943136960946;2518.508890766301;2524.4572416930264;2530.797536197109;2540.707818600866;2477.3278037324735;2492.314613481289;2508.7867531259867;2510.3724068982756;2515.7484251574697;11660.650180494598;11783.116266280063;11795.233309834834;11933.462994165284;11953.103517236155;6469.417752402259;6698.765543205965;6716.798496045078;6730.495212859007;6737.157855268091;3354.496825476174;4083.8570650027104;4276.3799249109015;4314.460692413449;4316.244657185011;1217.2331431198288;1252.0734656354323;1274.5793888017024;1280.9039322050883;1284.5953524639708;16433.931794433443;16563.751543726852;16579.336826527044;16593.336266549322;16608.335666573443;5512.614117011851;5644.238027866268;5679.403662615448;5730.398308177665;5758.337791153987;10278.588856445658;12194.451249693608;12224.816627750592;12231.266124032587;12241.387930603434;6336.936630633634;6488.837779055561;6524.119243902107;6548.607083575001;6572.539922205399;6110.541709371726;6135.170930383137;6165.815025549241;6179.227596550431;6197.659128747872;3784.4512545680723;4251.256030194703;4365;4397.802098905557;4474.194644963938;1013.8327176015874;1139.4758411130806;1244.937753112359;1249.1318533619049;1274.8342715446934;772.563501621582;776.4487214077955;785.5914924239363;799.2846402469758;802.438293194766;6606.7026983785845;6660.100886380374;7065.50541462103;7070.939359096176;7095.751648692271;902.273669695888;948.1798244518579;949.4161090929167;953.3612479638658;959.8512230604267;3868.7678739275734;3961.5246170459727;3964.524257089168;4066.6949978751713;4164.12553363797;14461.060031098155;15145.091294522144;15232.93369464149;15247.771283430757;15266.313015914311;14380.280985950661;14891.032082914577;15016.549503514912;15085.849141508665;15137.924310378377;8158.551279679675;8165.510069395856;8273.875891861628;8310.750677479686;8364.790880228044;5565.55475561946;6738.8652226956165;6847.794566163023;7031.859362812613;7067.858642827012;13278.468861245829;13753.518626848012;13773.311334433038;13942.163470191821;13953.790693139814;13229.603111906657;14087.718245634826;14099.647508812363;14194.290285485478;14198.3610737515;9423;9688.70933871985;9700.417974921527;9711.465869377253;9738.561764720464;9265.675701350421;10365.01532354425;10705.839412408664;10776.622818201638;10781.676549703521;9267.258619310574;9274.721758347261;9291.62833486653;9325.347225694137;9440.85838712406;9104.998450170608;9115.088491150831;9145.496997664926;9172.312076594415;9330.206932410822;8111.432199745961;8262.752117436487;8269.83460330802;8275.08974012846;8284.668613255402;5642.351129620146;6049.485793707584;6109.938900610937;6136.815895523141;6527.15147030883;5358.866028349323;5816.952948469316;6180.258368995749;6210.937890621036;6325.588836725598 15:36:14 INFO - PID 1647 | |4;prototype.html;392.28995518112214;392.91552316252;393.5769048273107;396.99603003969986;405.53161098930735;5026.773795179217;5490.3411590609085;5853.736581853811;6412.967935160324;6437.551550901045;5739.913901291474;6125.448709616134;6271.968640156804;6328.525360597962;6345.206849143857;4158.771267580282;4431.803413078467;4594.448666160066;4660.580547750702;4664.183767840631;4242.6605871530355;4267.765272909989;4442.689406625053;4668.6731928765;4669.486356500782;4605.516420775821;4611.746353950532;4613.63090952723;4627.236505976527;4629.166749985001;3917.5298964124486;4593.931091033637;4655.674102812804;4665.253559430509;4684.953150468486;3986.242613903349;3994;4017.678585713136;4020.979895100535;4036.809141303293;5931.347551769302;6247.219097612799;6277.686115694197;6285.43431091204;6315.273743519509;4742.719465744264;5440.809571664972;5481.0670396642945;5498.422665620093;5515.917261241085;6806.421454176351;6853.0531871295325;6865.674624986276;6872.072270243553;6915.101036865226;4528.9547104528865;4539.7049191802425;4539.931901021488;4550.158220729176;4567.1550763108935;6233.781817636406;6835.487338449636;6951.7914462566205;7201.89197162043;7211.89182162268;4285.7642829644665;4543.250363689954;4547.3406356078185;4577.542245775396;4580.152671755736;4327.853118923488;4651.976740116278;4663.207254766698;4669.719816811003;4676.50896655853;4588.587027167571;5001.874953126128;5455.536279416294;5472.621902478002;5491.670499770026;6517.9348206517325;6707.262201157869;6828.317168283132;6830.7609233676585;6838.931610683929;1472.9705405892037;1477.7955965887982;1580.857722804953;1582.3196025708826;1587.952361429159;1011.8937511561327;1028.6245520385005;1048.119579553179;1048.7116043088213;1049.7113293844254;5719.196734171813;5957.821265362046;6014.195741277599;6410.294867564564;6444.194475690539;6444.452221561126;6572.07990881277;7020.859582808417;7024.683889224997;7057.647117644097;6348.619082855043;6357.904631430532;6362.9045564316575;6365.872682546323;6405.6797160141805;3434.5019972103905;3672;3677.724170687209;3693.242885208526;3696.2792255510453;6671;6948.895766563505;7059.7529086482755;7251.3836323912;7262.273772622695;5468.343798744097;6187.566870319034;6747.4333674718555;7134;7231.602261875647;5465.371482279589;6854.554453960476;7026.156861176692;7262.745803896944;7319.341259286583;6691.899621505779;6957.478189135733;7022.683979221049;7055.013917136801;7134.286571342825;5857.94142058591;6078.3921607838865;6368.617882927039;6933.5146539743755;6974.930250697428;5859.9121013183985;7099.574025558483;7203.2076471589135;7272.818179545554;7281.817954551178;4079.1433798901376;4080.4491393661474;4448.199324121689;4560.8336040380555;4590.196715574828;7042.612656303748;7048.506604537838;7065.682044307916;7069.182641373601;7074.3633073023675;5413.458654134555;6789.253182149861;6825.1127353443735;6832.180138383425;6888.311168883071;5432.538234250133;6784.151981002375;6796.524243302922;6822.870100744833;6843.520953533404;6664.8000559983275;6666.366695163952;6666.766663166766;6672.399484046459;6692.0965669635925;6805.8638827222185;6808.9659551701925;6831.45348372139;6845.828854278683;6846.691898864464;5999.70001499932;6018.789342372996;6351.841203969938;6632.2372927114;6637.269900311058 15:36:14 INFO - PID 1647 | |5;yui.html;755.8601658693142;829.1376967953329;830.7839961609983;865.2472349056322;869.7564681889071;17180.742288865677;17480.300787968466;17708.734368984442;17718.468445946626;17739.645207095866;14401.20793356365;14692.853071469282;16511.83488165118;16646.916765416157;16679.16604169793;945.4563625915092;972.9805403891918;978.2320878110685;978.4422878958997;985.2561316206254;829.4401279136589;975.3903810118676;985.9309848310621;987.6938149173743;992.5136683025338;992.1963209800085;1029.428667089765;1036.160709825044;1040.8074506216337;1048.2714513413182;1032.958681652733;1043.2280112716574;1043.8799538053106;1045.0020230679759;1045.5295117197215;760;760.4258784617579;762.0816915616668;764.2815753192037;767.5816679909462;16900;17566.648667026715;17615.383461578782;17743.556411089696;18212.544686382815;17848.375306864196;17927.37254196097;17945.192466338915;18312.725309120375;18339.083045847656;12126.211796233216;14980.951333406665;15048.699026019527;15099.54701358961;15184.316705748268;3543.557055368079;3658.981705091484;3661.194537201838;3667.9266414671556;3689.3728066228537;105784;130541;131570;131923;132677.0098448524;13568.93215533916;13762.311884405739;13852.307384630729;13869.653258668613;13883.305834708224;26253.162278640695;26482.735172648026;26732.465350693266;26921.326966825593;26990.920363185254;7646.655900484491;7653.272939070779;7693.115291741409;7701.383889288843;7724.188960159102;3866.0528170597822;3926.764394136361;4008.6191811777835;4008.899777505586;4009.659178969762;4397.648188144941;4486.439195100613;4489;4489.124620698998;4491.685582009293;842.1241908415179;875.3784812782995;883.5228976352697;888.8488956877346;889.0531583863165;654.0646874968745;655.6590572902064;667.6094484726392;670.9429698475586;678.3182901184376;10120.342177758423;11359.864013598573;12163.391830408444;12165.513379464723;12196.207246528948;558.9860253493695;603.6619493083887;619.1582419868555;639.2935805934376;669.621663759974;5131.435542090367;5402.216678581583;5695.316562012502;5716.828495145152;5911.320198177179;132051;146553;150449.19385076864;155599.99600036052;155940;131614;132714;133604;135394;135921;105101.99999999847;110788;113676;114685;115283;12058.708045268322;12704.87295127074;12766.872331276569;12799.616011519669;13061;43473.39159650431;43512.56487435085;44838;45943.54056459526;46325.536744632125;53938.65153371197;57056.85886282334;59131;59679.61281548689;59832;16408.671826563164;17231.483055508354;17390.47828565145;17561.56096097608;17652.205650746004;18178.36375726843;18530.16614252389;18763.906180469556;18954.24183032663;19041.714374284118;17620.735688964418;17974.64050718952;18083.276668933097;18209;18221.180046897658;17153.828461715722;17295.654086918443;17581.560460988065;17810.287588496314;17972.730409043612;17351.30594776195;17405.216765245343;17505.474835754947;17552.648947021244;17642.38251661237;11720.941395292968;11842.28946263227;11982.10134239918;12054.09594280415;12120.878791212328;13233.206007639572;13818.723625527635;14307;14347.426102955766;14456.915731319983 15:36:14 INFO - PID 1647 | __end_tp_report 15:36:14 INFO - PID 1647 | __start_cc_report 15:36:14 INFO - PID 1647 | _x_x_mozilla_cycle_collect,449 15:36:14 INFO - PID 1647 | __end_cc_report 15:36:14 INFO - PID 1647 | __startTimestamp1496356574366__endTimestamp 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | ------- Summary: start ------- 15:36:14 INFO - PID 1647 | Number of tests: 6 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6208.24 Median:6394.90 stddev:766.49 (12.0%) stddev-sans-first:740.84 15:36:14 INFO - PID 1647 | Values: 3591.4 3598.6 3629.6 3635.1 3643.8 4644.0 6150.6 6174.0 6207.4 6242.3 4889.0 6186.3 6200.1 6214.3 6272.1 4772.7 5273.7 5470.8 5478.5 5535.1 5537.0 5580.7 5602.9 5635.0 5674.1 4808.9 5301.2 5463.2 5504.7 5519.7 5539.9 5550.3 5576.5 5582.3 5634.0 5498.9 5500.5 5511.3 5526.5 5538.9 6169.6 6191.1 6237.9 6255.6 6338.7 5569.4 6738.6 6759.8 6761.3 6763.5 5417.0 6904.3 6908.3 6912.3 6922.5 6225.9 6272.1 6349.1 6384.2 6389.8 6940.6 6998.1 7030.0 7036.9 7061.2 5483.2 6044.7 6248.7 6462.7 6675.1 6729.7 6765.5 6857.6 6880.8 6895.7 6724.3 6753.6 6759.6 6775.2 6778.6 6557.7 6645.4 6690.5 6770.5 6785.6 5421.5 5424.1 5427.5 5428.1 5460.6 4821.9 4829.0 4835.4 4840.9 4857.8 6130.1 6261.1 6288.3 6307.1 6316.4 6888.0 6893.9 6909.7 6929.2 6956.4 6298.7 6358.6 6364.8 6378.2 6394.9 5898.6 5967.8 6033.7 6119.4 6124.3 6945.4 7039.3 7053.3 7059.0 7061.0 6843.0 6888.7 6894.2 6927.4 6932.2 5847.8 6830.9 6968.7 7018.9 7033.5 6677.0 6724.9 6775.8 6784.0 6824.5 6875.7 6880.0 6883.8 6887.9 6931.8 6456.3 6543.1 6549.9 6589.8 6703.4 6505.0 6506.8 6594.1 6604.1 6635.0 6449.4 6527.4 6530.6 6547.0 6567.0 6610.0 6693.4 6693.6 6696.4 6704.2 6308.1 6389.3 6451.8 6509.8 6520.7 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:45288.62 Median:181431.33 stddev:77320.66 (42.6%) stddev-sans-first:77465.03 15:36:14 INFO - PID 1647 | Values: 680.7 784.5 838.9 1256.4 1299.3 33224.7 39669.6 40203.2 40240.2 40332.6 40516.6 40928.2 40993.0 41063.4 41073.8 7839.3 7999.8 8220.3 8468.2 8496.6 8349.8 8367.0 8435.9 8463.4 8464.1 8428.2 8431.5 8467.0 8488.9 8504.8 8446.9 8447.6 8451.9 8469.6 8498.8 8140.8 8197.2 8269.2 8275.4 8280.0 37704.8 37704.9 37942.4 37963.9 38029.4 3541.2 3580.1 3581.8 3582.0 3584.5 30674.4 30908.5 30928.5 30938.5 31019.8 3041.3 3082.6 3084.2 3086.8 3096.1 225666.0 245295.0 248967.8 271879.0 292090.0 48739.0 48747.8 48819.3 48970.5 48990.0 50003.0 50215.5 50534.5 50562.0 50613.0 14372.2 14496.9 14513.7 14539.6 14543.0 11704.5 11800.3 11844.6 11880.3 11897.9 13911.1 14016.7 14028.6 14106.4 14157.3 4828.0 5667.5 6145.7 6206.2 6275.8 986.5 1016.2 1041.7 1070.6 1074.7 23585.9 24832.5 24833.0 24856.3 25062.9 1023.9 1061.6 1074.1 1130.1 1156.9 12593.2 12716.9 12758.4 12780.2 12787.0 326884.0 328070.0 328543.0 329049.0 329638.0 54376.5 54563.5 54680.5 54739.9 54771.7 1485.2 1500.9 1524.2 1531.2 1540.9 24349.9 24629.8 24663.0 24703.5 24726.1 38169.9 50606.0 52149.2 52383.7 52419.4 291937.0 294013.5 295560.0 296059.0 296486.0 34216.0 36770.8 37012.8 37067.6 37085.4 44429.0 44432.0 46020.5 46601.8 46796.3 29683.4 30144.8 31562.8 31841.7 32145.8 25008.0 31049.7 31283.7 31926.8 31989.0 37522.9 38164.8 38235.9 38271.0 38332.3 30435.8 30678.8 31707.8 31968.9 32366.0 23728.5 24726.5 24956.0 25043.0 25436.4 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:37189.55 Median:11930.36 stddev:94009.53 (788.0%) stddev-sans-first:94232.57 15:36:14 INFO - PID 1647 | Values: 354.1 373.2 383.4 388.5 446.3 14686.4 15347.8 22355.8 22922.3 23111.0 22292.4 22954.8 23269.7 23271.0 23362.8 5538.3 6177.9 6357.1 6363.4 6371.7 3597.7 3815.6 6225.1 6317.8 6327.7 5901.2 6158.5 6186.0 6198.7 6205.6 6148.4 6185.9 6197.6 6199.8 6205.8 5548.1 5550.9 5553.9 5562.9 5564.1 27606.3 27848.3 28195.3 28521.9 28614.0 2186.9 2223.1 2226.9 2227.4 2234.4 18064.5 18388.4 18590.8 18600.6 18616.2 2029.8 2049.8 2091.9 2104.4 2131.7 167063.2 178619.0 187105.0 192824.0 194531.0 12324.8 12535.4 12710.5 12889.2 12899.4 16437.8 16448.6 16466.4 16490.8 16496.2 8969.1 9108.6 9109.0 9148.6 9204.1 7289.2 8704.6 8709.0 8752.5 8754.6 6728.5 7036.3 7140.3 7505.3 7702.5 1995.7 2116.9 2255.5 2258.5 2294.0 841.8 875.5 933.2 972.5 1012.4 20823.8 21310.8 21316.9 21404.7 21673.8 1008.1 1018.6 1027.6 1042.4 1047.7 7646.9 8769.4 9205.9 9661.5 10144.7 535699.0 540866.0 549569.0 549999.8 550812.0 107454.0 141040.0 141221.0 141765.0 141952.6 1165.5 1187.2 1208.0 1219.1 1224.4 18106.5 18450.4 18683.6 19060.0 19197.5 64884.4 64910.7 65523.0 65776.7 66408.7 66902.3 86744.0 87607.0 87766.0 87767.6 10023.8 10109.8 10181.7 10249.1 10256.2 12506.4 17914.5 17950.2 18105.5 18121.2 14125.0 14602.6 14622.9 14725.8 14748.5 12733.5 12983.9 13029.3 13173.1 13341.5 14425.7 14568.8 14740.3 14874.8 14908.4 10028.8 10040.1 10133.2 10136.9 10150.3 14556.7 14649.0 14734.5 14800.1 14802.0 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7162.41 Median:3826.61 stddev:4573.32 (119.5%) stddev-sans-first:4565.36 15:36:14 INFO - PID 1647 | Values: 1359.1 1421.1 1429.1 1438.8 1450.3 11446.3 11603.8 12172.2 12204.0 12217.8 11947.8 12146.2 12155.7 12175.5 12177.3 2511.8 2561.6 2568.3 2570.4 2574.6 2106.4 2118.3 2199.6 2466.4 2523.4 2538.5 2558.6 2559.7 2561.7 2564.2 2162.6 2518.5 2524.5 2530.8 2540.7 2477.3 2492.3 2508.8 2510.4 2515.7 11660.7 11783.1 11795.2 11933.5 11953.1 6469.4 6698.8 6716.8 6730.5 6737.2 3354.5 4083.9 4276.4 4314.5 4316.2 1217.2 1252.1 1274.6 1280.9 1284.6 16433.9 16563.8 16579.3 16593.3 16608.3 5512.6 5644.2 5679.4 5730.4 5758.3 10278.6 12194.5 12224.8 12231.3 12241.4 6336.9 6488.8 6524.1 6548.6 6572.5 6110.5 6135.2 6165.8 6179.2 6197.7 3784.5 4251.3 4365.0 4397.8 4474.2 1013.8 1139.5 1244.9 1249.1 1274.8 772.6 776.4 785.6 799.3 802.4 6606.7 6660.1 7065.5 7070.9 7095.8 902.3 948.2 949.4 953.4 959.9 3868.8 3961.5 3964.5 4066.7 4164.1 14461.1 15145.1 15232.9 15247.8 15266.3 14380.3 14891.0 15016.5 15085.8 15137.9 8158.6 8165.5 8273.9 8310.8 8364.8 5565.6 6738.9 6847.8 7031.9 7067.9 13278.5 13753.5 13773.3 13942.2 13953.8 13229.6 14087.7 14099.6 14194.3 14198.4 9423.0 9688.7 9700.4 9711.5 9738.6 9265.7 10365.0 10705.8 10776.6 10781.7 9267.3 9274.7 9291.6 9325.3 9440.9 9105.0 9115.1 9145.5 9172.3 9330.2 8111.4 8262.8 8269.8 8275.1 8284.7 5642.4 6049.5 6109.9 6136.8 6527.2 5358.9 5817.0 6180.3 6210.9 6325.6 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5443.45 Median:6048.59 stddev:1724.68 (28.5%) stddev-sans-first:1687.34 15:36:14 INFO - PID 1647 | Values: 392.3 392.9 393.6 397.0 405.5 5026.8 5490.3 5853.7 6413.0 6437.6 5739.9 6125.4 6272.0 6328.5 6345.2 4158.8 4431.8 4594.4 4660.6 4664.2 4242.7 4267.8 4442.7 4668.7 4669.5 4605.5 4611.7 4613.6 4627.2 4629.2 3917.5 4593.9 4655.7 4665.3 4685.0 3986.2 3994.0 4017.7 4021.0 4036.8 5931.3 6247.2 6277.7 6285.4 6315.3 4742.7 5440.8 5481.1 5498.4 5515.9 6806.4 6853.1 6865.7 6872.1 6915.1 4529.0 4539.7 4539.9 4550.2 4567.2 6233.8 6835.5 6951.8 7201.9 7211.9 4285.8 4543.3 4547.3 4577.5 4580.2 4327.9 4652.0 4663.2 4669.7 4676.5 4588.6 5001.9 5455.5 5472.6 5491.7 6517.9 6707.3 6828.3 6830.8 6838.9 1473.0 1477.8 1580.9 1582.3 1588.0 1011.9 1028.6 1048.1 1048.7 1049.7 5719.2 5957.8 6014.2 6410.3 6444.2 6444.5 6572.1 7020.9 7024.7 7057.6 6348.6 6357.9 6362.9 6365.9 6405.7 3434.5 3672.0 3677.7 3693.2 3696.3 6671.0 6948.9 7059.8 7251.4 7262.3 5468.3 6187.6 6747.4 7134.0 7231.6 5465.4 6854.6 7026.2 7262.7 7319.3 6691.9 6957.5 7022.7 7055.0 7134.3 5857.9 6078.4 6368.6 6933.5 6974.9 5859.9 7099.6 7203.2 7272.8 7281.8 4079.1 4080.4 4448.2 4560.8 4590.2 7042.6 7048.5 7065.7 7069.2 7074.4 5413.5 6789.3 6825.1 6832.2 6888.3 5432.5 6784.2 6796.5 6822.9 6843.5 6664.8 6666.4 6666.8 6672.4 6692.1 6805.9 6809.0 6831.5 6845.8 6846.7 5999.7 6018.8 6351.8 6632.2 6637.3 15:36:14 INFO - PID 1647 | 15:36:14 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25540.67 Median:17958.96 stddev:39247.54 (218.5%) stddev-sans-first:39313.52 15:36:14 INFO - PID 1647 | Values: 755.9 829.1 830.8 865.2 869.8 17180.7 17480.3 17708.7 17718.5 17739.6 14401.2 14692.9 16511.8 16646.9 16679.2 945.5 973.0 978.2 978.4 985.3 829.4 975.4 985.9 987.7 992.5 992.2 1029.4 1036.2 1040.8 1048.3 1033.0 1043.2 1043.9 1045.0 1045.5 760.0 760.4 762.1 764.3 767.6 16900.0 17566.6 17615.4 17743.6 18212.5 17848.4 17927.4 17945.2 18312.7 18339.1 12126.2 14981.0 15048.7 15099.5 15184.3 3543.6 3659.0 3661.2 3667.9 3689.4 105784.0 130541.0 131570.0 131923.0 132677.0 13568.9 13762.3 13852.3 13869.7 13883.3 26253.2 26482.7 26732.5 26921.3 26990.9 7646.7 7653.3 7693.1 7701.4 7724.2 3866.1 3926.8 4008.6 4008.9 4009.7 4397.6 4486.4 4489.0 4489.1 4491.7 842.1 875.4 883.5 888.8 889.1 654.1 655.7 667.6 670.9 678.3 10120.3 11359.9 12163.4 12165.5 12196.2 559.0 603.7 619.2 639.3 669.6 5131.4 5402.2 5695.3 5716.8 5911.3 132051.0 146553.0 150449.2 155600.0 155940.0 131614.0 132714.0 133604.0 135394.0 135921.0 105102.0 110788.0 113676.0 114685.0 115283.0 12058.7 12704.9 12766.9 12799.6 13061.0 43473.4 43512.6 44838.0 45943.5 46325.5 53938.7 57056.9 59131.0 59679.6 59832.0 16408.7 17231.5 17390.5 17561.6 17652.2 18178.4 18530.2 18763.9 18954.2 19041.7 17620.7 17974.6 18083.3 18209.0 18221.2 17153.8 17295.7 17581.6 17810.3 17972.7 17351.3 17405.2 17505.5 17552.6 17642.4 11720.9 11842.3 11982.1 12054.1 12120.9 13233.2 13818.7 14307.0 14347.4 14456.9 15:36:14 INFO - PID 1647 | -------- Summary: end -------- 15:36:14 INFO - PID 1647 | 15:36:14 INFO - TEST-INFO | 1647: exit 0 15:36:14 INFO - TEST-OK | dromaeo_css | took 1143326ms 15:36:14 INFO - TEST-START | kraken 15:36:14 INFO - operating with platform_type : mac_ 15:36:14 INFO - Initialising browser for kraken test... 15:36:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpmeC2UD/profile 15:36:14 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49231/getInfo.html -foreground) 15:36:48 INFO - TEST-INFO | 1657: killed by SIGTERM 15:36:48 INFO - Browser initialized. 15:36:48 INFO - Running cycle 1/1 for kraken test... 15:36:48 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpmeC2UD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:50 INFO - PID 1661 | 2017-06-01 15:36:50.647 plugin-container[1662:10510] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 15:36:50 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:50 INFO - PID 1661 | 2017-06-01 15:36:50.647 plugin-container[1662:10510] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 15:36:50 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:50 INFO - PID 1661 | 2017-06-01 15:36:50.647 plugin-container[1662:10510] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:36:51 INFO - PID 1661 | 2017-06-01 15:36:51.373 plugin-container[1663:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 15:36:51 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:51 INFO - PID 1661 | 2017-06-01 15:36:51.373 plugin-container[1663:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a13, name = 'com.apple.CFPasteboardClient' 15:36:51 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:36:51 INFO - PID 1661 | 2017-06-01 15:36:51.373 plugin-container[1663:10563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:37:00 INFO - PID 1661 | RSS: Main: 259862528 15:37:00 INFO - PID 1661 | 15:37:03 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection) 15:37:04 INFO - PID 1661 | RSS: Main: 270643200 15:37:04 INFO - PID 1661 | 15:37:06 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-dft) 15:37:07 INFO - PID 1661 | RSS: Main: 279629824 15:37:07 INFO - PID 1661 | 15:37:11 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-fft) 15:37:11 INFO - PID 1661 | RSS: Main: 281817088 15:37:11 INFO - PID 1661 | 15:37:13 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator) 15:37:14 INFO - PID 1661 | RSS: Main: 284114944 15:37:14 INFO - PID 1661 | 15:37:16 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:37:17 INFO - PID 1661 | RSS: Main: 284045312 15:37:17 INFO - PID 1661 | 15:37:19 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom) 15:37:20 INFO - PID 1661 | RSS: Main: 289767424 15:37:20 INFO - PID 1661 | 15:37:23 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate) 15:37:24 INFO - PID 1661 | RSS: Main: 293220352 15:37:24 INFO - PID 1661 | 15:37:27 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial) 15:37:27 INFO - PID 1661 | RSS: Main: 291868672 15:37:27 INFO - PID 1661 | 15:37:29 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:37:29 INFO - PID 1661 | RSS: Main: 291860480 15:37:29 INFO - PID 1661 | 15:37:31 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:37:32 INFO - PID 1661 | RSS: Main: 291848192 15:37:32 INFO - PID 1661 | 15:37:34 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:35 INFO - PID 1661 | RSS: Main: 291676160 15:37:35 INFO - PID 1661 | 15:37:38 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:38 INFO - PID 1661 | RSS: Main: 292057088 15:37:38 INFO - PID 1661 | 15:37:41 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:42 INFO - PID 1661 | RSS: Main: 291557376 15:37:42 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49231/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49231/tests/kraken/driver.html?testName=ai-astar) 15:37:44 INFO - PID 1661 | RSS: Main: 293494784 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | __start_tp_report 15:37:44 INFO - PID 1661 | _x_x_mozilla_page_load 15:37:44 INFO - PID 1661 | _x_x_mozilla_page_load_details 15:37:44 INFO - PID 1661 | |i|pagename|runs| 15:37:44 INFO - PID 1661 | |0;ai-astar;101;115;113;114;112;113;114;112;122;112 15:37:44 INFO - PID 1661 | |1;audio-beat-detection;144;105;123;144;108;132;97;146;156;143 15:37:44 INFO - PID 1661 | |2;audio-dft;197;200;196;221;218;227;275;200;274;269 15:37:44 INFO - PID 1661 | |3;audio-fft;89;85;83;70;83;82;87;56;85;49 15:37:44 INFO - PID 1661 | |4;audio-oscillator;88;69;85;71;68;68;85;68;70;97 15:37:44 INFO - PID 1661 | |5;imaging-gaussian-blur;88;88;88;88;89;88;87;89;87;87 15:37:44 INFO - PID 1661 | |6;imaging-darkroom;98;97;117;151;131;94;96;94;96;101 15:37:44 INFO - PID 1661 | |7;imaging-desaturate;91;94;91;90;93;91;91;93;91;108 15:37:44 INFO - PID 1661 | |8;json-parse-financial;51;49;49;50;51;52;50;51;51;49 15:37:44 INFO - PID 1661 | |9;json-stringify-tinderbox;55;56;62;55;61;59;56;55;56;56 15:37:44 INFO - PID 1661 | |10;stanford-crypto-aes;87;89;91;90;86;87;89;90;128;113 15:37:44 INFO - PID 1661 | |11;stanford-crypto-ccm;138;153;155;148;138;139;137;140;136;160 15:37:44 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;186;191;184;184;226;170;227;229;187;186 15:37:44 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;66;68;68;67;68;65;69;66;70;66 15:37:44 INFO - PID 1661 | __end_tp_report 15:37:44 INFO - PID 1661 | __start_cc_report 15:37:44 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1198 15:37:44 INFO - PID 1661 | __end_cc_report 15:37:44 INFO - PID 1661 | __startTimestamp1496356664482__endTimestamp 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | ------- Summary: start ------- 15:37:44 INFO - PID 1661 | Number of tests: 14 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.50 stddev:5.09 (4.5%) stddev-sans-first:3.14 15:37:44 INFO - PID 1661 | Values: 101.0 115.0 113.0 114.0 112.0 113.0 114.0 112.0 122.0 112.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:144.00 stddev:20.39 (14.2%) stddev-sans-first:20.97 15:37:44 INFO - PID 1661 | Values: 144.0 105.0 123.0 144.0 108.0 132.0 97.0 146.0 156.0 143.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:227.70 Median:224.00 stddev:32.84 (14.7%) stddev-sans-first:32.90 15:37:44 INFO - PID 1661 | Values: 197.0 200.0 196.0 221.0 218.0 227.0 275.0 200.0 274.0 269.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:76.90 Median:84.00 stddev:13.92 (16.6%) stddev-sans-first:14.05 15:37:44 INFO - PID 1661 | Values: 89.0 85.0 83.0 70.0 83.0 82.0 87.0 56.0 85.0 49.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.00 stddev:10.75 (13.8%) stddev-sans-first:10.63 15:37:44 INFO - PID 1661 | Values: 88.0 69.0 85.0 71.0 68.0 68.0 85.0 68.0 70.0 97.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 15:37:44 INFO - PID 1661 | Values: 88.0 88.0 88.0 88.0 89.0 88.0 87.0 89.0 87.0 87.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:95.00 stddev:19.46 (20.5%) stddev-sans-first:20.33 15:37:44 INFO - PID 1661 | Values: 98.0 97.0 117.0 151.0 131.0 94.0 96.0 94.0 96.0 101.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:91.00 stddev:5.31 (5.8%) stddev-sans-first:5.57 15:37:44 INFO - PID 1661 | Values: 91.0 94.0 91.0 90.0 93.0 91.0 91.0 93.0 91.0 108.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:51.00 stddev:1.06 (2.1%) stddev-sans-first:1.09 15:37:44 INFO - PID 1661 | Values: 51.0 49.0 49.0 50.0 51.0 52.0 50.0 51.0 51.0 49.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:56.00 stddev:2.60 (4.6%) stddev-sans-first:2.65 15:37:44 INFO - PID 1661 | Values: 55.0 56.0 62.0 55.0 61.0 59.0 56.0 55.0 56.0 56.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:89.00 stddev:13.98 (15.7%) stddev-sans-first:14.53 15:37:44 INFO - PID 1661 | Values: 87.0 89.0 91.0 90.0 86.0 87.0 89.0 90.0 128.0 113.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:144.00 stddev:8.81 (6.1%) stddev-sans-first:9.03 15:37:44 INFO - PID 1661 | Values: 138.0 153.0 155.0 148.0 138.0 139.0 137.0 140.0 136.0 160.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.00 Median:189.00 stddev:21.63 (11.4%) stddev-sans-first:22.57 15:37:44 INFO - PID 1661 | Values: 186.0 191.0 184.0 184.0 226.0 170.0 227.0 229.0 187.0 186.0 15:37:44 INFO - PID 1661 | 15:37:44 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.57 (2.3%) stddev-sans-first:1.59 15:37:44 INFO - PID 1661 | Values: 66.0 68.0 68.0 67.0 68.0 65.0 69.0 66.0 70.0 66.0 15:37:44 INFO - PID 1661 | -------- Summary: end -------- 15:37:44 INFO - PID 1661 | 15:37:45 INFO - TEST-INFO | 1661: exit 0 15:37:45 INFO - TEST-OK | kraken | took 90570ms 15:37:45 INFO - SUITE-END | took 1233s 15:37:45 INFO - Completed test suite (00:20:34) 15:37:45 INFO - javascript benchmark 15:37:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8066.670108730479, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3591.4074177760654, 3598.6041535431104, 3629.618890016549, 3635.1457407509247, 3643.8360273787675, 4644.0, 6150.600210986283, 6174.0, 6207.410296021871, 6242.344553821851, 4889.046635905991, 6186.257649082105, 6200.10098535712, 6214.254289485257, 6272.1219029335925, 4772.665913386073, 5273.709945952971, 5470.835874923759, 5478.479544443291, 5535.086710692731, 5537.031019571579, 5580.720963951807, 5602.943970560283, 5635.0, 5674.063779476382, 4808.903821923577, 5301.151815709506, 5463.235147079412, 5504.697241651707, 5519.69641669708, 5539.889202215933, 5550.27846379972, 5576.498115169654, 5582.302212223472, 5634.014047541664, 5498.862528436781, 5500.532454741352, 5511.283533140666, 5526.50261476465, 5538.9169162462595, 6169.598976066542, 6191.133241346215, 6237.937620623781, 6255.593386429913, 6338.746450141989, 5569.359523654791, 6738.629375384401, 6759.797206083826, 6761.29006454325, 6763.526553141282, 5417.0, 6904.344087311748, 6908.3437073477935, 6912.343327383941, 6922.480813938974, 6225.875482490324, 6272.1219029335925, 6349.079383489415, 6384.201974753156, 6389.808305750835, 6940.583564986117, 6998.125234345707, 7030.0, 7036.9296307036275, 7061.152661680631, 5483.204935284361, 6044.697765111727, 6248.687565621701, 6462.741490340428, 6675.065490831287, 6729.730810767613, 6765.492588055817, 6857.588544687235, 6880.793576192722, 6895.655217239118, 6724.327567243335, 6753.628550429774, 6759.5606285591275, 6775.1531058617675, 6778.627175505395, 6557.737690492422, 6645.401913827779, 6690.5316627836555, 6770.492213084039, 6785.626790526569, 5421.4578542146255, 5424.077906755861, 5427.511523962862, 5428.0500912340185, 5460.645058071213, 4821.855344339676, 4828.95171048285, 4835.3714017178245, 4840.854774356775, 4857.80568777252, 6130.122981477994, 6261.092141639528, 6288.339724328971, 6307.085472606446, 6316.368363163647, 6887.965560172267, 6893.896591551131, 6909.689063992032, 6929.237783843773, 6956.373926346653, 6298.685065746694, 6358.586691865014, 6364.77723279683, 6378.170837791057, 6394.904076438857, 5898.557608179318, 5967.791127310524, 6033.698315084228, 6119.388061193845, 6124.295705993868, 6945.444364450731, 7039.331263529956, 7053.329933656294, 7059.0, 7060.976158457098, 6842.965785171242, 6888.724451021903, 6894.172699276119, 6927.376536111774, 6932.168139823253, 5847.8245652630485, 6830.863382732218, 6968.6864091115, 7018.859622807618, 7033.542819716702, 6676.9666151668935, 6724.93275067243, 6775.830604234933, 6784.0163176338165, 6824.454043676395, 6875.656217189221, 6880.0, 6883.793486195422, 6887.931120688729, 6931.826704332432, 6456.289808121009, 6543.116679248241, 6549.901751473827, 6589.80230593083, 6703.430208432338, 6505.024246362894, 6506.804795856132, 6594.076829244005, 6604.075429439787, 6635.0379195015485, 6449.419552240321, 6527.412532872065, 6530.575512591667, 6546.985217291328, 6567.0, 6609.966950165219, 6693.364130407604, 6693.598384096969, 6696.397324240646, 6704.195496540447, 6308.116863639002, 6389.2652344980925, 6451.774187903401, 6509.804705858832, 6520.706568204348], "value": 6158.377478402291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.6902859199064, 784.4626430894838, 838.8573942429788, 1256.3843716578874, 1299.3113649765628, 33224.66775332246, 39669.60330396695, 40203.1959360813, 40240.195196096094, 40332.59667403325, 40516.59483405165, 40928.181436371255, 40993.0, 41063.384049239285, 41073.7946310268, 7839.255270749284, 7999.760007199764, 8220.260176584106, 8468.195521425485, 8496.617652205665, 8349.833003339929, 8367.0, 8435.873461898076, 8463.4075614707, 8464.1112683168, 8428.199321064489, 8431.536265505394, 8467.0, 8488.872666910001, 8504.829903401927, 8446.873296900552, 8447.57762111898, 8451.873221901646, 8469.576521173916, 8498.83002339956, 8140.75577732669, 8197.180281971756, 8269.173082691743, 8275.379346548973, 8279.95860020696, 37704.81147594272, 37704.86885393443, 37942.43086353707, 37963.86108416752, 38029.42955855664, 3541.1501239702543, 3580.104973756561, 3581.767185132958, 3581.964180358189, 3584.480250363709, 30674.38651226963, 30908.53637195444, 30928.536071958937, 30938.53592196119, 31019.84490077535, 3041.3461105862125, 3082.645495767993, 3084.228942764309, 3086.7993580417424, 3096.0866544369464, 225665.99999999837, 245295.0, 248967.75516122484, 271879.0, 292090.0, 48739.0, 48747.75626121847, 48819.267710984364, 48970.51029489731, 48990.020199595805, 50003.0, 50215.49784502181, 50534.49465505298, 50562.0, 50613.0, 14372.209528475825, 14496.927515362566, 14513.709725805636, 14539.563813085624, 14542.981991260722, 11704.47329870158, 11800.291982481078, 11844.644660660195, 11880.287182769061, 11897.88102118985, 13911.095778774346, 14016.719665606835, 14028.579142625533, 14106.435742570182, 14157.292135393394, 4828.03439312139, 5667.4615911488345, 6145.678679084019, 6206.162168107338, 6275.843103922347, 986.5166068626321, 1016.192127258831, 1041.6927006533092, 1070.6092276319243, 1074.7420619051372, 23585.882070589538, 24832.50334993326, 24833.0, 24856.254312370656, 25062.874685626455, 1023.9129673977646, 1061.6125114333213, 1074.1460538871615, 1130.141092769501, 1156.8785277545906, 12593.181443206162, 12716.936415318049, 12758.425870835834, 12780.169288996185, 12787.0, 326884.0, 328070.0, 328543.0, 329049.0, 329638.0, 54376.45623543793, 54563.45436545663, 54680.45319546833, 54739.90520189574, 54771.726141369036, 1485.2499487758612, 1500.9099454032794, 1524.1693277163972, 1531.1502116325323, 1540.9075455472707, 24349.87825060863, 24629.753702462745, 24663.0, 24703.505929880943, 24726.13458528943, 38169.85490435291, 50606.0, 52149.21776173285, 52383.73808130935, 52419.42741717755, 291937.0, 294013.529932349, 295560.0, 296059.0, 296486.0, 34216.0, 36770.816145919096, 37012.81493592515, 37067.62932370642, 37085.443718343704, 44429.0, 44432.0, 46020.539794601624, 46601.834954125065, 46796.29805552851, 29683.406331873673, 30144.84927575348, 31562.842185788923, 31841.681583183872, 32145.839270803495, 25008.0, 31049.68950310468, 31283.687163128077, 31926.840365798023, 31989.0, 37522.87431377063, 38164.809175953946, 38235.85292441231, 38271.0, 38332.27504762237, 30435.847820760755, 30678.846605766827, 31707.841460792548, 31968.88108916177, 32366.0, 23728.52542949166, 24726.50546989014, 24956.0, 25043.0, 25436.364090897874], "value": 17850.2659351394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.14650691832674, 373.21252157946725, 383.41720584711237, 388.50150102852643, 446.3215911814043, 14686.412543498274, 15347.769783453257, 22355.776442235572, 22922.312330630088, 23110.960006799713, 22292.442688932744, 22954.770452295474, 23269.650955235644, 23270.95280712368, 23362.766372336315, 5538.252335934658, 6177.9382206177925, 6357.141785858896, 6363.4272915437605, 6371.713272902707, 3597.748157628967, 3815.5802861685193, 6225.128482012522, 6317.842053948642, 6327.715252813635, 5901.173835662989, 6158.476529494998, 6185.969070154643, 6198.721057552421, 6205.565610407273, 6148.446639802417, 6185.907211391833, 6197.628142311476, 6199.752009919598, 6205.844853878667, 5548.0845660465975, 5550.86122846928, 5553.944460555384, 5562.916556251619, 5564.081926482166, 27606.309842253904, 27848.303792405353, 28195.295117622223, 28521.85912563495, 28614.0, 2186.9343919682433, 2223.055201539339, 2226.8552544084746, 2227.376334626307, 2234.430220293831, 18064.548386290317, 18388.448346549623, 18590.814091859043, 18600.627987440177, 18616.162272697762, 2029.8477614178996, 2049.815516603513, 2091.937241882746, 2104.4006555018295, 2131.7335333083365, 167063.16468417703, 178619.0, 187105.0, 192824.0, 194531.0, 12324.81512777309, 12535.373231338397, 12710.491580336682, 12889.162204456681, 12899.419526121346, 16437.83562164387, 16448.588785280463, 16466.42367517155, 16490.83509164893, 16496.17519124039, 8969.058248883774, 9108.590113444778, 9108.954455227682, 9148.588313525906, 9204.079592040742, 7289.161746399232, 8704.564771761387, 8709.042005379402, 8752.518611476302, 8754.606042728092, 6728.495362847806, 7036.296370362923, 7140.285971402922, 7505.324520793155, 7702.460827742004, 1995.712765266424, 2116.8835714035877, 2255.5150642611916, 2258.491839336169, 2293.9541209175723, 841.7643059943226, 875.4922145155795, 933.2114363362947, 972.5429048347407, 1012.427978192319, 20823.79176208249, 21310.78689213119, 21316.893415532824, 21404.678929816066, 21673.783262167177, 1008.0977525114981, 1018.5930497172848, 1027.5530144387214, 1042.3693665332517, 1047.7171163785736, 7646.885296720554, 8769.429987050822, 9205.907940920504, 9661.516924153764, 10144.695659130239, 535699.0, 540866.0, 549569.0, 549999.7500037503, 550812.0, 107454.0, 141040.0, 141221.0, 141765.0, 141952.580474196, 1165.4813607944552, 1187.1511869013696, 1207.957721479744, 1219.1039585904352, 1224.4428784902848, 18106.54733631617, 18450.446486605422, 18683.626327473645, 19060.0, 19197.520061998563, 64884.35115648972, 64910.67544662247, 65523.0, 65776.67111664411, 66408.6679566599, 66902.31683782283, 86744.0, 87607.0, 87766.0, 87767.56116219379, 10023.847257565474, 10109.848352274576, 10181.694549163536, 10249.12882405004, 10256.179505639382, 12506.374681266083, 17914.46256612304, 17950.19224134891, 18105.547361316072, 18121.184546695167, 14125.0, 14602.561923142322, 14622.853771462575, 14725.779113313094, 14748.483803066842, 12733.490660373482, 12983.870161298266, 13029.348532573524, 13173.077884547989, 13341.533046343331, 14425.711485770436, 14568.781468278195, 14740.336684849422, 14874.776878346616, 14908.403663853325, 10028.799424011333, 10040.146587539848, 10133.18934485254, 10136.949315253376, 10150.340227885164, 14556.708865822413, 14649.0, 14734.484293049692, 14800.111993280007, 14802.0], "value": 10802.078733134826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.1029920252633, 1421.133108803629, 1429.0996672096585, 1438.798603166356, 1450.282110355374, 11446.255993360446, 11603.825942610867, 12172.208806427587, 12204.0, 12217.755644887096, 11947.805219478048, 12146.210496317733, 12155.696107597292, 12175.51297948081, 12177.33024683642, 2511.7739403453684, 2561.6157576363603, 2568.319395360231, 2570.421655127591, 2574.562324404846, 2106.357560943919, 2118.2691971269965, 2199.604071267171, 2466.371075375777, 2523.4448421347274, 2538.530371881199, 2558.616207568861, 2559.7056338521124, 2561.718210996789, 2564.205096420106, 2162.5943136960946, 2518.508890766301, 2524.4572416930264, 2530.797536197109, 2540.707818600866, 2477.3278037324735, 2492.314613481289, 2508.7867531259867, 2510.3724068982756, 2515.7484251574697, 11660.650180494598, 11783.116266280063, 11795.233309834834, 11933.462994165284, 11953.103517236155, 6469.417752402259, 6698.765543205965, 6716.798496045078, 6730.495212859007, 6737.157855268091, 3354.496825476174, 4083.8570650027104, 4276.3799249109015, 4314.460692413449, 4316.244657185011, 1217.2331431198288, 1252.0734656354323, 1274.5793888017024, 1280.9039322050883, 1284.5953524639708, 16433.931794433443, 16563.751543726852, 16579.336826527044, 16593.336266549322, 16608.335666573443, 5512.614117011851, 5644.238027866268, 5679.403662615448, 5730.398308177665, 5758.337791153987, 10278.588856445658, 12194.451249693608, 12224.816627750592, 12231.266124032587, 12241.387930603434, 6336.936630633634, 6488.837779055561, 6524.119243902107, 6548.607083575001, 6572.539922205399, 6110.541709371726, 6135.170930383137, 6165.815025549241, 6179.227596550431, 6197.659128747872, 3784.4512545680723, 4251.256030194703, 4365.0, 4397.802098905557, 4474.194644963938, 1013.8327176015874, 1139.4758411130806, 1244.937753112359, 1249.1318533619049, 1274.8342715446934, 772.563501621582, 776.4487214077955, 785.5914924239363, 799.2846402469758, 802.438293194766, 6606.7026983785845, 6660.100886380374, 7065.50541462103, 7070.939359096176, 7095.751648692271, 902.273669695888, 948.1798244518579, 949.4161090929167, 953.3612479638658, 959.8512230604267, 3868.7678739275734, 3961.5246170459727, 3964.524257089168, 4066.6949978751713, 4164.12553363797, 14461.060031098155, 15145.091294522144, 15232.93369464149, 15247.771283430757, 15266.313015914311, 14380.280985950661, 14891.032082914577, 15016.549503514912, 15085.849141508665, 15137.924310378377, 8158.551279679675, 8165.510069395856, 8273.875891861628, 8310.750677479686, 8364.790880228044, 5565.55475561946, 6738.8652226956165, 6847.794566163023, 7031.859362812613, 7067.858642827012, 13278.468861245829, 13753.518626848012, 13773.311334433038, 13942.163470191821, 13953.790693139814, 13229.603111906657, 14087.718245634826, 14099.647508812363, 14194.290285485478, 14198.3610737515, 9423.0, 9688.70933871985, 9700.417974921527, 9711.465869377253, 9738.561764720464, 9265.675701350421, 10365.01532354425, 10705.839412408664, 10776.622818201638, 10781.676549703521, 9267.258619310574, 9274.721758347261, 9291.62833486653, 9325.347225694137, 9440.85838712406, 9104.998450170608, 9115.088491150831, 9145.496997664926, 9172.312076594415, 9330.206932410822, 8111.432199745961, 8262.752117436487, 8269.83460330802, 8275.08974012846, 8284.668613255402, 5642.351129620146, 6049.485793707584, 6109.938900610937, 6136.815895523141, 6527.15147030883, 5358.866028349323, 5816.952948469316, 6180.258368995749, 6210.937890621036, 6325.588836725598], "value": 5387.141905042908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.28995518112214, 392.91552316252, 393.5769048273107, 396.99603003969986, 405.53161098930735, 5026.773795179217, 5490.3411590609085, 5853.736581853811, 6412.967935160324, 6437.551550901045, 5739.913901291474, 6125.448709616134, 6271.968640156804, 6328.525360597962, 6345.206849143857, 4158.771267580282, 4431.803413078467, 4594.448666160066, 4660.580547750702, 4664.183767840631, 4242.6605871530355, 4267.765272909989, 4442.689406625053, 4668.6731928765, 4669.486356500782, 4605.516420775821, 4611.746353950532, 4613.63090952723, 4627.236505976527, 4629.166749985001, 3917.5298964124486, 4593.931091033637, 4655.674102812804, 4665.253559430509, 4684.953150468486, 3986.242613903349, 3994.0, 4017.678585713136, 4020.979895100535, 4036.809141303293, 5931.347551769302, 6247.219097612799, 6277.686115694197, 6285.43431091204, 6315.273743519509, 4742.719465744264, 5440.809571664972, 5481.0670396642945, 5498.422665620093, 5515.917261241085, 6806.421454176351, 6853.0531871295325, 6865.674624986276, 6872.072270243553, 6915.101036865226, 4528.9547104528865, 4539.7049191802425, 4539.931901021488, 4550.158220729176, 4567.1550763108935, 6233.781817636406, 6835.487338449636, 6951.7914462566205, 7201.89197162043, 7211.89182162268, 4285.7642829644665, 4543.250363689954, 4547.3406356078185, 4577.542245775396, 4580.152671755736, 4327.853118923488, 4651.976740116278, 4663.207254766698, 4669.719816811003, 4676.50896655853, 4588.587027167571, 5001.874953126128, 5455.536279416294, 5472.621902478002, 5491.670499770026, 6517.9348206517325, 6707.262201157869, 6828.317168283132, 6830.7609233676585, 6838.931610683929, 1472.9705405892037, 1477.7955965887982, 1580.857722804953, 1582.3196025708826, 1587.952361429159, 1011.8937511561327, 1028.6245520385005, 1048.119579553179, 1048.7116043088213, 1049.7113293844254, 5719.196734171813, 5957.821265362046, 6014.195741277599, 6410.294867564564, 6444.194475690539, 6444.452221561126, 6572.07990881277, 7020.859582808417, 7024.683889224997, 7057.647117644097, 6348.619082855043, 6357.904631430532, 6362.9045564316575, 6365.872682546323, 6405.6797160141805, 3434.5019972103905, 3672.0, 3677.724170687209, 3693.242885208526, 3696.2792255510453, 6671.0, 6948.895766563505, 7059.7529086482755, 7251.3836323912, 7262.273772622695, 5468.343798744097, 6187.566870319034, 6747.4333674718555, 7134.0, 7231.602261875647, 5465.371482279589, 6854.554453960476, 7026.156861176692, 7262.745803896944, 7319.341259286583, 6691.899621505779, 6957.478189135733, 7022.683979221049, 7055.013917136801, 7134.286571342825, 5857.94142058591, 6078.3921607838865, 6368.617882927039, 6933.5146539743755, 6974.930250697428, 5859.9121013183985, 7099.574025558483, 7203.2076471589135, 7272.818179545554, 7281.817954551178, 4079.1433798901376, 4080.4491393661474, 4448.199324121689, 4560.8336040380555, 4590.196715574828, 7042.612656303748, 7048.506604537838, 7065.682044307916, 7069.182641373601, 7074.3633073023675, 5413.458654134555, 6789.253182149861, 6825.1127353443735, 6832.180138383425, 6888.311168883071, 5432.538234250133, 6784.151981002375, 6796.524243302922, 6822.870100744833, 6843.520953533404, 6664.8000559983275, 6666.366695163952, 6666.766663166766, 6672.399484046459, 6692.0965669635925, 6805.8638827222185, 6808.9659551701925, 6831.45348372139, 6845.828854278683, 6846.691898864464, 5999.70001499932, 6018.789342372996, 6351.841203969938, 6632.2372927114, 6637.269900311058], "value": 4900.8897757091245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.8601658693142, 829.1376967953329, 830.7839961609983, 865.2472349056322, 869.7564681889071, 17180.742288865677, 17480.300787968466, 17708.734368984442, 17718.468445946626, 17739.645207095866, 14401.20793356365, 14692.853071469282, 16511.83488165118, 16646.916765416157, 16679.16604169793, 945.4563625915092, 972.9805403891918, 978.2320878110685, 978.4422878958997, 985.2561316206254, 829.4401279136589, 975.3903810118676, 985.9309848310621, 987.6938149173743, 992.5136683025338, 992.1963209800085, 1029.428667089765, 1036.160709825044, 1040.8074506216337, 1048.2714513413182, 1032.958681652733, 1043.2280112716574, 1043.8799538053106, 1045.0020230679759, 1045.5295117197215, 760.0, 760.4258784617579, 762.0816915616668, 764.2815753192037, 767.5816679909462, 16900.0, 17566.648667026715, 17615.383461578782, 17743.556411089696, 18212.544686382815, 17848.375306864196, 17927.37254196097, 17945.192466338915, 18312.725309120375, 18339.083045847656, 12126.211796233216, 14980.951333406665, 15048.699026019527, 15099.54701358961, 15184.316705748268, 3543.557055368079, 3658.981705091484, 3661.194537201838, 3667.9266414671556, 3689.3728066228537, 105784.0, 130541.0, 131570.0, 131923.0, 132677.0098448524, 13568.93215533916, 13762.311884405739, 13852.307384630729, 13869.653258668613, 13883.305834708224, 26253.162278640695, 26482.735172648026, 26732.465350693266, 26921.326966825593, 26990.920363185254, 7646.655900484491, 7653.272939070779, 7693.115291741409, 7701.383889288843, 7724.188960159102, 3866.0528170597822, 3926.764394136361, 4008.6191811777835, 4008.899777505586, 4009.659178969762, 4397.648188144941, 4486.439195100613, 4489.0, 4489.124620698998, 4491.685582009293, 842.1241908415179, 875.3784812782995, 883.5228976352697, 888.8488956877346, 889.0531583863165, 654.0646874968745, 655.6590572902064, 667.6094484726392, 670.9429698475586, 678.3182901184376, 10120.342177758423, 11359.864013598573, 12163.391830408444, 12165.513379464723, 12196.207246528948, 558.9860253493695, 603.6619493083887, 619.1582419868555, 639.2935805934376, 669.621663759974, 5131.435542090367, 5402.216678581583, 5695.316562012502, 5716.828495145152, 5911.320198177179, 132051.0, 146553.0, 150449.19385076864, 155599.99600036052, 155940.0, 131614.0, 132714.0, 133604.0, 135394.0, 135921.0, 105101.99999999847, 110788.0, 113676.0, 114685.0, 115283.0, 12058.708045268322, 12704.87295127074, 12766.872331276569, 12799.616011519669, 13061.0, 43473.39159650431, 43512.56487435085, 44838.0, 45943.54056459526, 46325.536744632125, 53938.65153371197, 57056.85886282334, 59131.0, 59679.61281548689, 59832.0, 16408.671826563164, 17231.483055508354, 17390.47828565145, 17561.56096097608, 17652.205650746004, 18178.36375726843, 18530.16614252389, 18763.906180469556, 18954.24183032663, 19041.714374284118, 17620.735688964418, 17974.64050718952, 18083.276668933097, 18209.0, 18221.180046897658, 17153.828461715722, 17295.654086918443, 17581.560460988065, 17810.287588496314, 17972.730409043612, 17351.30594776195, 17405.216765245343, 17505.474835754947, 17552.648947021244, 17642.38251661237, 11720.941395292968, 11842.28946263227, 11982.10134239918, 12054.09594280415, 12120.878791212328, 13233.206007639572, 13818.723625527635, 14307.0, 14347.426102955766, 14456.915731319983], "value": 8787.865938374918, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 113.0, 114.0, 112.0, 113.0, 114.0, 112.0, 122.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 105.0, 123.0, 144.0, 108.0, 132.0, 97.0, 146.0, 156.0, 143.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 196.0, 221.0, 218.0, 227.0, 275.0, 200.0, 274.0, 269.0], "value": 227.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 83.0, 70.0, 83.0, 82.0, 87.0, 56.0, 85.0, 49.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 85.0, 71.0, 68.0, 68.0, 85.0, 68.0, 70.0, 97.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 88.0, 89.0, 88.0, 87.0, 89.0, 87.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 117.0, 151.0, 131.0, 94.0, 96.0, 94.0, 96.0, 101.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 91.0, 90.0, 93.0, 91.0, 91.0, 93.0, 91.0, 108.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 50.0, 51.0, 52.0, 50.0, 51.0, 51.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 62.0, 55.0, 61.0, 59.0, 56.0, 55.0, 56.0, 56.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 91.0, 90.0, 86.0, 87.0, 89.0, 90.0, 128.0, 113.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 153.0, 155.0, 148.0, 138.0, 139.0, 137.0, 140.0, 136.0, 160.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 191.0, 184.0, 184.0, 226.0, 170.0, 227.0, 229.0, 187.0, 186.0], "value": 197.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 67.0, 68.0, 65.0, 69.0, 66.0, 70.0, 66.0], "value": 67.3, "unit": "score"}]}]} 15:37:45 INFO - Return code: 0 15:37:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:37:45 INFO - # TBPL SUCCESS # 15:37:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:37:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:37:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:37:45 INFO - 2017-06-01 15:37:45.888 screenresolution[1669:10950] starting screenresolution argv=screenresolution get 15:37:45 INFO - 2017-06-01 15:37:45.892 screenresolution[1669:10950] Display 0: 1600x1200x32@60 15:37:45 INFO - 2017-06-01 15:37:45.898 screenresolution[1670:10953] starting screenresolution argv=screenresolution list 15:37:45 INFO - Available Modes on Display 0 15:37:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:37:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:37:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:37:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:37:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:37:46 INFO - Intel Iris: 15:37:46 INFO - Chipset Model: Intel Iris 15:37:46 INFO - Type: GPU 15:37:46 INFO - Bus: Built-In 15:37:46 INFO - VRAM (Dynamic, Max): 1536 MB 15:37:46 INFO - Vendor: Intel (0x8086) 15:37:46 INFO - Device ID: 0x0a2e 15:37:46 INFO - Revision ID: 0x0009 15:37:46 INFO - Displays: 15:37:46 INFO - Display: 15:37:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:37:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:37:46 INFO - Display Serial Number: 1600x1200 60 15:37:46 INFO - Main Display: Yes 15:37:46 INFO - Mirror: Off 15:37:46 INFO - Online: Yes 15:37:46 INFO - Rotation: Supported 15:37:46 INFO - Return code: 0 15:37:46 INFO - Running post-action listener: _resource_record_post_action 15:37:46 INFO - [mozharness: 2017-06-01 22:37:46.136660Z] Finished run-tests step (success) 15:37:46 INFO - Running post-run listener: _resource_record_post_run 15:37:46 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 51013120; Write bytes: 569426944; Read time: 597; Write time: 1945 15:37:46 INFO - TinderboxPrint: CPU usage
53.3% 15:37:46 INFO - TinderboxPrint: I/O read bytes / time
51,013,120 / 597 15:37:46 INFO - TinderboxPrint: I/O write bytes / time
569,426,944 / 1,945 15:37:46 INFO - TinderboxPrint: CPU idle
2,344.7 (46.7%) 15:37:46 INFO - TinderboxPrint: CPU system
1,013.7 (20.2%) 15:37:46 INFO - TinderboxPrint: CPU user
1,667.4 (33.2%) 15:37:46 INFO - TinderboxPrint: Swap in / out
287,641,600 / 0 15:37:46 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 239391232; Write bytes: 231479296; Read time: 16910; Write time: 569 15:37:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:37:46 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 48858624; Write bytes: 319577088; Read time: 570; Write time: 1331 15:37:46 INFO - Running post-run listener: _upload_blobber_files 15:37:46 INFO - Blob upload gear active. 15:37:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:46 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 15:37:46 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'] 15:37:46 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 15:37:47 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:37:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:37:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:47 INFO - (blobuploader) - INFO - Done attempting. 15:37:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:37:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:37:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:50 INFO - (blobuploader) - INFO - Done attempting. 15:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:37:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:51 INFO - (blobuploader) - INFO - Done attempting. 15:37:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:37:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:37:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:53 INFO - (blobuploader) - INFO - Done attempting. 15:37:53 INFO - (blobuploader) - INFO - Iteration through files over. 15:37:53 INFO - Return code: 0 15:37:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:37:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:37:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a40a4216a6aae57c457d47fc7aa170bd5679d4436b1db8410c8d1320629aefa644b8cb31deda08cdbd583a3fb8dcae3c2a701d57e917408274898a5cac31c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2622f307b7f5567cf9ecb896736cc53e2e5cb14b5f31fbc2d88ad4eb599bca3f894888d7e2d8883b694da547bc6ba61f7e680db7e37829d9158a41cca1072871", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1890c7289c72b46784941102adfd2e951d0b6d2a464434c8c5e02fca4c7f8b84f230d4c2a0b7d6386dbd17dc2284e66125ae95da86dae008f37397cde94e9ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75afe84ce32bdfb8a23586bf6ab768ba217e1a466aa7836ace37f509df779157f7dfc2465ebbf9eed16b39ac44a20edeb0e9cd1e4d8813093edd0995c57a76d5"} 15:37:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:37:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:37:53 INFO - Contents: 15:37:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a40a4216a6aae57c457d47fc7aa170bd5679d4436b1db8410c8d1320629aefa644b8cb31deda08cdbd583a3fb8dcae3c2a701d57e917408274898a5cac31c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2622f307b7f5567cf9ecb896736cc53e2e5cb14b5f31fbc2d88ad4eb599bca3f894888d7e2d8883b694da547bc6ba61f7e680db7e37829d9158a41cca1072871", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1890c7289c72b46784941102adfd2e951d0b6d2a464434c8c5e02fca4c7f8b84f230d4c2a0b7d6386dbd17dc2284e66125ae95da86dae008f37397cde94e9ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75afe84ce32bdfb8a23586bf6ab768ba217e1a466aa7836ace37f509df779157f7dfc2465ebbf9eed16b39ac44a20edeb0e9cd1e4d8813093edd0995c57a76d5"} 15:37:53 INFO - Running post-run listener: copy_logs_to_upload_dir 15:37:53 INFO - Copying logs to upload dir... 15:37:53 INFO - mkdir: /builds/slave/test/build/upload/logs 15:37:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.351348 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-01 15:37:53.381074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 15:37:53.388824) ========= 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.nrkImdaf67/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.9i14qheRth/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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/25a40a4216a6aae57c457d47fc7aa170bd5679d4436b1db8410c8d1320629aefa644b8cb31deda08cdbd583a3fb8dcae3c2a701d57e917408274898a5cac31c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2622f307b7f5567cf9ecb896736cc53e2e5cb14b5f31fbc2d88ad4eb599bca3f894888d7e2d8883b694da547bc6ba61f7e680db7e37829d9158a41cca1072871", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1890c7289c72b46784941102adfd2e951d0b6d2a464434c8c5e02fca4c7f8b84f230d4c2a0b7d6386dbd17dc2284e66125ae95da86dae008f37397cde94e9ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75afe84ce32bdfb8a23586bf6ab768ba217e1a466aa7836ace37f509df779157f7dfc2465ebbf9eed16b39ac44a20edeb0e9cd1e4d8813093edd0995c57a76d5"} build_url:https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/Z_y6pr3VRSOlrkIDYHqXZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25a40a4216a6aae57c457d47fc7aa170bd5679d4436b1db8410c8d1320629aefa644b8cb31deda08cdbd583a3fb8dcae3c2a701d57e917408274898a5cac31c6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2622f307b7f5567cf9ecb896736cc53e2e5cb14b5f31fbc2d88ad4eb599bca3f894888d7e2d8883b694da547bc6ba61f7e680db7e37829d9158a41cca1072871", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1890c7289c72b46784941102adfd2e951d0b6d2a464434c8c5e02fca4c7f8b84f230d4c2a0b7d6386dbd17dc2284e66125ae95da86dae008f37397cde94e9ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75afe84ce32bdfb8a23586bf6ab768ba217e1a466aa7836ace37f509df779157f7dfc2465ebbf9eed16b39ac44a20edeb0e9cd1e4d8813093edd0995c57a76d5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 15:37:53.426252) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:37:53.426849) ========= 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.nrkImdaf67/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.9i14qheRth/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005186 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:37:53.478525) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 15:37:53.479114) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 15:37:53.479751) ========= ========= Total master_lag: 0.23 =========