builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0274 starttime: 1496854492.62 results: success (0) revision: a49112c7a5765802096b3fc298069b9495436107 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.618290) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.618960) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.619418) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.646525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.647047) ========= 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.CSaXgmznA6/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.qOqhLjOYs4/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-07 09:54:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-06-07 09:54:52 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140651 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.939896) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.940225) ========= 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.CSaXgmznA6/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.qOqhLjOYs4/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.026588 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:52.987565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-07 09:54:52.987901) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a49112c7a5765802096b3fc298069b9495436107 --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 a49112c7a5765802096b3fc298069b9495436107 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CSaXgmznA6/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.qOqhLjOYs4/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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-07 09:54:53,084 truncating revision to first 12 chars 2017-06-07 09:54:53,084 Setting DEBUG logging. 2017-06-07 09:54:53,084 attempt 1/10 2017-06-07 09:54:53,084 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a49112c7a576?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-07 09:54:55,043 unpacking tar archive at: mozilla-central-a49112c7a576/testing/mozharness/ program finished with exit code 0 elapsedTime=2.242403 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-07 09:54:55.252184) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:55.252556) ========= script_repo_revision: a49112c7a5765802096b3fc298069b9495436107 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:55.252986) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:55.253268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-07 09:54:55.272453) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-07 09:54:55.272849) ========= /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.CSaXgmznA6/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.qOqhLjOYs4/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:54:55 INFO - MultiFileLogger online at 20170607 09:54:55 in /builds/slave/test 09:54:55 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 09:54:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:54:55 INFO - {'append_to_log': False, 09:54:55 INFO - 'base_work_dir': '/builds/slave/test', 09:54:55 INFO - 'blob_upload_branch': 'Firefox', 09:54:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:54:55 INFO - 'branch': 'Firefox', 09:54:55 INFO - 'buildbot_json_path': 'buildprops.json', 09:54:55 INFO - 'config_files': ('talos/mac_config.py',), 09:54:55 INFO - 'default_actions': ('clobber', 09:54:55 INFO - 'read-buildbot-config', 09:54:55 INFO - 'download-and-extract', 09:54:55 INFO - 'populate-webroot', 09:54:55 INFO - 'create-virtualenv', 09:54:55 INFO - 'install', 09:54:55 INFO - 'setup-mitmproxy', 09:54:55 INFO - 'run-tests'), 09:54:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:54:55 INFO - 'download_minidump_stackwalk': True, 09:54:55 INFO - 'download_symbols': 'ondemand', 09:54:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:54:55 INFO - 'tooltool.py': '/tools/tooltool.py', 09:54:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:54:55 INFO - '/tools/misc-python/virtualenv.py')}, 09:54:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:54:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:54:55 INFO - 'gecko_profile': False, 09:54:55 INFO - 'gecko_profile_interval': 0, 09:54:55 INFO - 'installer_path': 'installer.exe', 09:54:55 INFO - 'log_level': 'info', 09:54:55 INFO - 'log_name': 'talos', 09:54:55 INFO - 'log_to_console': True, 09:54:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:54:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:54:55 INFO - 'opt_config_files': (), 09:54:55 INFO - 'pip_index': False, 09:54:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:55 INFO - 'cmd': ('bash', 09:54:55 INFO - '-c', 09:54:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:55 INFO - 'enabled': True, 09:54:55 INFO - 'halt_on_failure': False, 09:54:55 INFO - 'name': 'check_screen_resolution'},), 09:54:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:55 INFO - 'cmd': ('bash', 09:54:55 INFO - '-c', 09:54:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:55 INFO - 'enabled': True, 09:54:55 INFO - 'halt_on_failure': False, 09:54:55 INFO - 'name': 'check_screen_resolution'},), 09:54:55 INFO - 'run_cmd_checks_enabled': True, 09:54:55 INFO - 'suite': 'dromaeojs-e10s', 09:54:55 INFO - 'system_bits': '32', 09:54:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:54:55 INFO - 'title': 't-yosemite-r7-0274', 09:54:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:54:55 INFO - 'use_talos_json': True, 09:54:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:54:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:54:55 INFO - 'work_dir': 'build'} 09:54:55 INFO - [mozharness: 2017-06-07 16:54:55.478135Z] Running clobber step. 09:54:55 INFO - Running pre-action listener: _resource_record_pre_action 09:54:55 INFO - Running main action method: clobber 09:54:55 INFO - rmtree: /builds/slave/test/build 09:54:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:54:57 INFO - Running post-action listener: _resource_record_post_action 09:54:57 INFO - [mozharness: 2017-06-07 16:54:57.835838Z] Finished clobber step (success) 09:54:57 INFO - [mozharness: 2017-06-07 16:54:57.835912Z] Running read-buildbot-config step. 09:54:57 INFO - Running pre-action listener: _resource_record_pre_action 09:54:57 INFO - Running main action method: read_buildbot_config 09:54:57 INFO - Using buildbot properties: 09:54:57 INFO - { 09:54:57 INFO - "project": "", 09:54:57 INFO - "product": "firefox", 09:54:57 INFO - "who": "ffxbld@noreply.mozilla.org", 09:54:57 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:54:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:54:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:54:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:54:57 INFO - "stage_platform": "macosx64", 09:54:57 INFO - "basedir": "/builds/slave/test", 09:54:57 INFO - "buildnumber": 81, 09:54:57 INFO - "platform": "yosemite_r7", 09:54:57 INFO - "parent_task_id": "TiHy-PZ7RzSK79SesJHHmw", 09:54:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:54:57 INFO - "slavebuilddir": "test", 09:54:57 INFO - "taskId": "DZvNRH52TiWI_F-LfgIRMQ", 09:54:57 INFO - "branch": "mozilla-central", 09:54:57 INFO - "script_repo_revision": "production", 09:54:57 INFO - "revision": "a49112c7a5765802096b3fc298069b9495436107", 09:54:57 INFO - "slavename": "t-yosemite-r7-0274", 09:54:57 INFO - "repo_path": "mozilla-central" 09:54:57 INFO - } 09:54:57 INFO - Finding installer, test and symbols from parent task. 09:54:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DZvNRH52TiWI_F-LfgIRMQ'}, attempt #1 09:54:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DZvNRH52TiWI_F-LfgIRMQ'}, attempt #1 09:54:59 INFO - Task dependencies: ckdoMZXjToadMHgRdVIt6g TiHy-PZ7RzSK79SesJHHmw 09:54:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw'}, attempt #1 09:54:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:54:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/test_packages.json 09:54:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/target.crashreporter-symbols.zip 09:54:59 INFO - Running post-action listener: _resource_record_post_action 09:54:59 INFO - [mozharness: 2017-06-07 16:54:59.648469Z] Finished read-buildbot-config step (success) 09:54:59 INFO - [mozharness: 2017-06-07 16:54:59.648613Z] Running download-and-extract step. 09:54:59 INFO - Running pre-action listener: _resource_record_pre_action 09:54:59 INFO - Running main action method: download_and_extract 09:54:59 INFO - mkdir: /builds/slave/test/build/tests 09:54:59 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')]} 09:54:59 INFO - trying https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:54:59 INFO - Downloading https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/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 09:54:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/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 09:55:00 INFO - Downloaded 1328 bytes. 09:55:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:55:00 INFO - Using the following test package requirements: 09:55:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:55:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:55:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:55:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:55:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'jsshell-mac.zip'], 09:55:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:55:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:55:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:55:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:55:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:55:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:55:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:55:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:55:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:55:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:55:00 INFO - Fetch https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:55:02 INFO - Content-Length response header: 57643301 09:55:02 INFO - Bytes received: 57643301 09:55:20 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 09:55:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:55:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:55:20 INFO - Fetch https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:55:22 INFO - Content-Length response header: 57643301 09:55:22 INFO - Bytes received: 57643301 09:55:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:55:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:55:39 INFO - Fetch https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:55:40 INFO - Content-Length response header: 14447563 09:55:40 INFO - Bytes received: 14447563 09:55:40 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')]} 09:55:40 INFO - trying https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:55:40 INFO - Downloading https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:55:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/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 09:55:42 INFO - Downloaded 68026426 bytes. 09:55:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:55:42 INFO - mkdir: /builds/slave/test/properties 09:55:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:55:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:55:42 INFO - Contents: 09:55:42 INFO - build_url:https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:55:42 INFO - Running post-action listener: _resource_record_post_action 09:55:42 INFO - Running post-action listener: set_extra_try_arguments 09:55:42 INFO - [mozharness: 2017-06-07 16:55:42.751146Z] Finished download-and-extract step (success) 09:55:42 INFO - [mozharness: 2017-06-07 16:55:42.751286Z] Running populate-webroot step. 09:55:42 INFO - Running pre-action listener: _resource_record_pre_action 09:55:42 INFO - Running main action method: populate_webroot 09:55:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:42 INFO - '--tppagecycles', 09:55:42 INFO - '7'], 09:55:42 INFO - 'tests': ['tsvgm']}}, 09:55:42 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:55:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:55:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:55:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['damp', 'tps']}, 09:55:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'tests': ['damp', 'tps']}, 09:55:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['dromaeo_dom']}, 09:55:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:55:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:55:42 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:55:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['a11yr', 09:55:42 INFO - 'ts_paint', 09:55:42 INFO - 'tpaint', 09:55:42 INFO - 'sessionrestore', 09:55:42 INFO - 'sessionrestore_no_auto_restore', 09:55:42 INFO - 'tabpaint']}, 09:55:42 INFO - 'other-e10s': {'tests': ['a11yr', 09:55:42 INFO - 'ts_paint', 09:55:42 INFO - 'tpaint', 09:55:42 INFO - 'sessionrestore', 09:55:42 INFO - 'sessionrestore_no_auto_restore', 09:55:42 INFO - 'tabpaint']}, 09:55:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:55:42 INFO - 'ts_paint', 09:55:42 INFO - 'tpaint', 09:55:42 INFO - 'sessionrestore', 09:55:42 INFO - 'sessionrestore_no_auto_restore', 09:55:42 INFO - 'tabpaint']}, 09:55:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:55:42 INFO - 'ts_paint', 09:55:42 INFO - 'tpaint', 09:55:42 INFO - 'sessionrestore', 09:55:42 INFO - 'sessionrestore_no_auto_restore', 09:55:42 INFO - 'tabpaint']}, 09:55:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['a11yr', 09:55:42 INFO - 'ts_paint', 09:55:42 INFO - 'tpaint', 09:55:42 INFO - 'sessionrestore', 09:55:42 INFO - 'sessionrestore_no_auto_restore', 09:55:42 INFO - 'tabpaint']}, 09:55:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['a11yr', 09:55:42 INFO - 'ts_paint', 09:55:42 INFO - 'tpaint', 09:55:42 INFO - 'sessionrestore', 09:55:42 INFO - 'sessionrestore_no_auto_restore', 09:55:42 INFO - 'tabpaint']}, 09:55:42 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:55:42 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:55:42 INFO - 'bloom_basic_ref']}, 09:55:42 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:55:42 INFO - 'talos_options': ['--mitmproxy', 09:55:42 INFO - 'mitmproxy-recording-1.mp', 09:55:42 INFO - '--firstNonBlankPaint'], 09:55:42 INFO - 'tests': ['Quantum_1']}, 09:55:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['tsvgx', 09:55:42 INFO - 'tsvgr_opacity', 09:55:42 INFO - 'tart', 09:55:42 INFO - 'tscrollx', 09:55:42 INFO - 'cart', 09:55:42 INFO - 'tsvg_static']}, 09:55:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:55:42 INFO - 'tsvgr_opacity', 09:55:42 INFO - 'tart', 09:55:42 INFO - 'tscrollx', 09:55:42 INFO - 'cart', 09:55:42 INFO - 'tsvg_static']}, 09:55:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'talos_options': ['--disable-e10s'], 09:55:42 INFO - 'tests': ['tp5o']}, 09:55:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:55:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'talos_options': ['--disable-e10s', 09:55:42 INFO - '--xperf_path', 09:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:42 INFO - 'tests': ['tp5n']}, 09:55:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:55:42 INFO - 'talos_options': ['--xperf_path', 09:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:42 INFO - 'tests': ['tp5n']}}, 09:55:42 INFO - 'talos.zip': {'path': '', 09:55:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:42 INFO - Running post-action listener: _resource_record_post_action 09:55:42 INFO - [mozharness: 2017-06-07 16:55:42.773167Z] Finished populate-webroot step (success) 09:55:42 INFO - [mozharness: 2017-06-07 16:55:42.773327Z] Running create-virtualenv step. 09:55:42 INFO - Running pre-action listener: _resource_record_pre_action 09:55:42 INFO - Running main action method: create_virtualenv 09:55:42 INFO - Creating virtualenv /builds/slave/test/build/venv 09:55:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:55:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:55:42 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:55:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:42 INFO - Using real prefix '/tools/python27' 09:55:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:55:43 INFO - Installing distribute.............................................................................................................................................................................................done. 09:55:46 INFO - Installing pip.................done. 09:55:46 INFO - Return code: 0 09:55:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:55:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:55:46 INFO - Reading from file tmpfile_stdout 09:55:46 INFO - Output received: 09:55:46 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) 09:55:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:55:46 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')]} 09:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:46 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')]} 09:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:46 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:46 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 09:55:46 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 09:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:46 INFO - 'HOME': '/Users/cltbld', 09:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:46 INFO - 'LOGNAME': 'cltbld', 09:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:46 INFO - 'MOZ_NO_REMOTE': '1', 09:55:46 INFO - 'NO_EM_RESTART': '1', 09:55:46 INFO - 'PAGER': '/bin/cat', 09:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:46 INFO - 'PWD': '/builds/slave/test', 09:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:46 INFO - 'SHELL': '/bin/bash', 09:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:46 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:46 INFO - 'USER': 'cltbld', 09:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:46 INFO - 'XPC_FLAGS': '0x0', 09:55:46 INFO - 'XPC_SERVICE_NAME': '0', 09:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:55:47 INFO - Cleaning up... 09:55:47 INFO - Return code: 0 09:55:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55: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')]} 09:55:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:47 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:47 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 09:55:47 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 09:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:47 INFO - 'HOME': '/Users/cltbld', 09:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:47 INFO - 'LOGNAME': 'cltbld', 09:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:47 INFO - 'MOZ_NO_REMOTE': '1', 09:55:47 INFO - 'NO_EM_RESTART': '1', 09:55:47 INFO - 'PAGER': '/bin/cat', 09:55:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:47 INFO - 'PWD': '/builds/slave/test', 09:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:47 INFO - 'SHELL': '/bin/bash', 09:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:47 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:47 INFO - 'USER': 'cltbld', 09:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:47 INFO - 'XPC_FLAGS': '0x0', 09:55:47 INFO - 'XPC_SERVICE_NAME': '0', 09:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:47 INFO - Downloading/unpacking psutil>=3.1.1 09:55: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 09:55: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 09:55: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 09:55: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 09:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:55:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:49 INFO - Installing collected packages: psutil 09:55:49 INFO - Running setup.py install for psutil 09:55:49 INFO - building 'psutil._psutil_osx' extension 09:55:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:55:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:55:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:55:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:55:50 INFO - building 'psutil._psutil_posix' extension 09:55:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:55:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:55:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:55:50 INFO - ^ 09:55:50 INFO - 1 warning generated. 09:55:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:55:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:50 INFO - Successfully installed psutil 09:55:50 INFO - Cleaning up... 09:55:50 INFO - Return code: 0 09:55:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:55:50 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')]} 09:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:50 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')]} 09:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:51 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 09:55:51 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 09:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:51 INFO - 'HOME': '/Users/cltbld', 09:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:51 INFO - 'LOGNAME': 'cltbld', 09:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:51 INFO - 'MOZ_NO_REMOTE': '1', 09:55:51 INFO - 'NO_EM_RESTART': '1', 09:55:51 INFO - 'PAGER': '/bin/cat', 09:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:51 INFO - 'PWD': '/builds/slave/test', 09:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:51 INFO - 'SHELL': '/bin/bash', 09:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:51 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:51 INFO - 'USER': 'cltbld', 09:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:51 INFO - 'XPC_FLAGS': '0x0', 09:55:51 INFO - 'XPC_SERVICE_NAME': '0', 09:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:55:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:55:53 INFO - Installing collected packages: mozsystemmonitor 09:55:53 INFO - Running setup.py install for mozsystemmonitor 09:55:53 INFO - Successfully installed mozsystemmonitor 09:55:53 INFO - Cleaning up... 09:55:53 INFO - Return code: 0 09:55:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:55:53 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')]} 09:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:53 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')]} 09:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:53 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:53 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 09:55:53 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 09:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:53 INFO - 'HOME': '/Users/cltbld', 09:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:53 INFO - 'LOGNAME': 'cltbld', 09:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:53 INFO - 'MOZ_NO_REMOTE': '1', 09:55:53 INFO - 'NO_EM_RESTART': '1', 09:55:53 INFO - 'PAGER': '/bin/cat', 09:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:53 INFO - 'PWD': '/builds/slave/test', 09:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:53 INFO - 'SHELL': '/bin/bash', 09:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:53 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:53 INFO - 'USER': 'cltbld', 09:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:53 INFO - 'XPC_FLAGS': '0x0', 09:55:53 INFO - 'XPC_SERVICE_NAME': '0', 09:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:55:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:55 INFO - Downloading functools32-3.2.3-2.tar.gz 09:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:55:55 INFO - warning: no files found matching '*.txt' 09:55:55 INFO - no previously-included directories found matching 'build' 09:55:55 INFO - no previously-included directories found matching 'dist' 09:55:55 INFO - no previously-included directories found matching '.git*' 09:55:55 INFO - Installing collected packages: jsonschema, functools32 09:55:55 INFO - Running setup.py install for functools32 09:55:55 INFO - warning: no files found matching '*.txt' 09:55:55 INFO - no previously-included directories found matching 'build' 09:55:55 INFO - no previously-included directories found matching 'dist' 09:55:55 INFO - no previously-included directories found matching '.git*' 09:55:55 INFO - Successfully installed jsonschema functools32 09:55:55 INFO - Cleaning up... 09:55:55 INFO - Return code: 0 09:55:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:55:55 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')]} 09:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:55 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')]} 09:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:55 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:55 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 09:55:55 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 09:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:55 INFO - 'HOME': '/Users/cltbld', 09:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:55 INFO - 'LOGNAME': 'cltbld', 09:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:55 INFO - 'MOZ_NO_REMOTE': '1', 09:55:55 INFO - 'NO_EM_RESTART': '1', 09:55:55 INFO - 'PAGER': '/bin/cat', 09:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:55 INFO - 'PWD': '/builds/slave/test', 09:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:55 INFO - 'SHELL': '/bin/bash', 09:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:55 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:55 INFO - 'USER': 'cltbld', 09:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:55 INFO - 'XPC_FLAGS': '0x0', 09:55:55 INFO - 'XPC_SERVICE_NAME': '0', 09:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:55:55 INFO - Cleaning up... 09:55:55 INFO - Return code: 0 09:55:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:55:55 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')]} 09:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:55 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')]} 09:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:55 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:55 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 09:55:55 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 09:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:55 INFO - 'HOME': '/Users/cltbld', 09:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:55 INFO - 'LOGNAME': 'cltbld', 09:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:55 INFO - 'MOZ_NO_REMOTE': '1', 09:55:55 INFO - 'NO_EM_RESTART': '1', 09:55:55 INFO - 'PAGER': '/bin/cat', 09:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:55 INFO - 'PWD': '/builds/slave/test', 09:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:55 INFO - 'SHELL': '/bin/bash', 09:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:55 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:55 INFO - 'USER': 'cltbld', 09:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:55 INFO - 'XPC_FLAGS': '0x0', 09:55:55 INFO - 'XPC_SERVICE_NAME': '0', 09:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:57 INFO - Downloading blobuploader-1.2.4.tar.gz 09:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:55:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:55:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:58 INFO - Downloading docopt-0.6.1.tar.gz 09:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:55:58 INFO - Installing collected packages: blobuploader, requests, docopt 09:55:58 INFO - Running setup.py install for blobuploader 09:55:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:55:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:55:58 INFO - Running setup.py install for requests 09:55:59 INFO - Running setup.py install for docopt 09:55:59 INFO - Successfully installed blobuploader requests docopt 09:55:59 INFO - Cleaning up... 09:55:59 INFO - Return code: 0 09:55:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:59 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')]} 09:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:59 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')]} 09:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:59 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:55:59 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 09:55:59 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 09:55:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:55:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:59 INFO - 'HOME': '/Users/cltbld', 09:55:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:59 INFO - 'LOGNAME': 'cltbld', 09:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:59 INFO - 'MOZ_NO_REMOTE': '1', 09:55:59 INFO - 'NO_EM_RESTART': '1', 09:55:59 INFO - 'PAGER': '/bin/cat', 09:55:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:59 INFO - 'PWD': '/builds/slave/test', 09:55:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:59 INFO - 'SHELL': '/bin/bash', 09:55:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:55:59 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:55:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:59 INFO - 'USER': 'cltbld', 09:55:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:59 INFO - 'XPC_FLAGS': '0x0', 09:55:59 INFO - 'XPC_SERVICE_NAME': '0', 09:55:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-t1NjR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-jykG1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-IZNfCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-tErFJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:59 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-_3JGVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-uynX1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-EvfGJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-cl24zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-iM_94F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-8wLLJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-RYyU76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-VAiFKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-GIyI5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gsnWo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:00 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-eI_lJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:01 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-DHphWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:01 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-yn62th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:01 INFO - Running setup.py install for manifestparser 09:56:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:01 INFO - Running setup.py install for mozcrash 09:56:01 INFO - Running setup.py install for mozdebug 09:56:01 INFO - Running setup.py install for mozdevice 09:56:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:01 INFO - Running setup.py install for mozfile 09:56:01 INFO - Running setup.py install for mozhttpd 09:56:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Running setup.py install for mozinfo 09:56:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Running setup.py install for mozInstall 09:56:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Running setup.py install for mozleak 09:56:02 INFO - Running setup.py install for mozlog 09:56:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Running setup.py install for moznetwork 09:56:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:02 INFO - Running setup.py install for mozprocess 09:56:03 INFO - Running setup.py install for mozprofile 09:56:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:03 INFO - Running setup.py install for mozrunner 09:56:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:03 INFO - Running setup.py install for mozscreenshot 09:56:03 INFO - Running setup.py install for moztest 09:56:03 INFO - Running setup.py install for mozversion 09:56:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:03 INFO - Cleaning up... 09:56:03 INFO - Return code: 0 09:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:03 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')]} 09:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:03 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')]} 09:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:03 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:03 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 09:56:03 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 09:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:03 INFO - 'HOME': '/Users/cltbld', 09:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:03 INFO - 'LOGNAME': 'cltbld', 09:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:03 INFO - 'MOZ_NO_REMOTE': '1', 09:56:03 INFO - 'NO_EM_RESTART': '1', 09:56:03 INFO - 'PAGER': '/bin/cat', 09:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:03 INFO - 'PWD': '/builds/slave/test', 09:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:03 INFO - 'SHELL': '/bin/bash', 09:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:56:03 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:03 INFO - 'USER': 'cltbld', 09:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:03 INFO - 'XPC_FLAGS': '0x0', 09:56:03 INFO - 'XPC_SERVICE_NAME': '0', 09:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-EHbA5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-rqVlz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:04 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)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-7tGbNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-er5QDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:04 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)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gSVyqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gFielf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-RVsg7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-rLFqBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:04 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-XyOpQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-CWFbOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:05 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)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-pUxmNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-v_hvv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:05 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)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-6KPjRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-gClTrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:05 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)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-yiuXXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-GSY36S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:05 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)) 09:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:05 INFO - Running setup.py (path:/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/pip-P0M68e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:56:05 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)) 09:56:05 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)) 09:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:56:05 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)) 09:56:05 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)) 09:56:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:07 INFO - Downloading blessings-1.6.tar.gz 09:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:07 INFO - Installing collected packages: blessings 09:56:07 INFO - Running setup.py install for blessings 09:56:07 INFO - Successfully installed blessings 09:56:07 INFO - Cleaning up... 09:56:07 INFO - Return code: 0 09:56:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:07 INFO - Reading from file tmpfile_stdout 09:56:07 INFO - Current package versions: 09:56:07 INFO - blessings == 1.6 09:56:07 INFO - blobuploader == 1.2.4 09:56:07 INFO - docopt == 0.6.1 09:56:07 INFO - functools32 == 3.2.3-2 09:56:07 INFO - jsonschema == 2.5.1 09:56:07 INFO - manifestparser == 1.1 09:56:07 INFO - mozInstall == 1.12 09:56:07 INFO - mozcrash == 1.0 09:56:07 INFO - mozdebug == 0.1 09:56:07 INFO - mozdevice == 0.51 09:56:07 INFO - mozfile == 1.2 09:56:07 INFO - mozhttpd == 0.7 09:56:07 INFO - mozinfo == 0.10 09:56:07 INFO - mozleak == 0.1 09:56:07 INFO - mozlog == 3.4 09:56:07 INFO - moznetwork == 0.27 09:56:07 INFO - mozprocess == 0.25 09:56:07 INFO - mozprofile == 0.28 09:56:07 INFO - mozrunner == 6.13 09:56:07 INFO - mozscreenshot == 0.1 09:56:07 INFO - mozsystemmonitor == 0.3 09:56:07 INFO - moztest == 0.8 09:56:07 INFO - mozversion == 1.4 09:56:07 INFO - psutil == 3.1.1 09:56:07 INFO - requests == 1.2.3 09:56:07 INFO - wsgiref == 0.1.2 09:56:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:07 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')]} 09:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:07 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')]} 09:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:07 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:07 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 09:56:07 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 09:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:07 INFO - 'HOME': '/Users/cltbld', 09:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:07 INFO - 'LOGNAME': 'cltbld', 09:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:07 INFO - 'MOZ_NO_REMOTE': '1', 09:56:07 INFO - 'NO_EM_RESTART': '1', 09:56:07 INFO - 'PAGER': '/bin/cat', 09:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:07 INFO - 'PWD': '/builds/slave/test', 09:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:07 INFO - 'SHELL': '/bin/bash', 09:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:56:07 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:07 INFO - 'USER': 'cltbld', 09:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:07 INFO - 'XPC_FLAGS': '0x0', 09:56:07 INFO - 'XPC_SERVICE_NAME': '0', 09:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:56:08 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:09 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:56:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:56:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:56:09 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)) 09:56:09 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)) 09:56:09 INFO - Installing collected packages: marionette-driver 09:56:09 INFO - Running setup.py install for marionette-driver 09:56:09 INFO - Successfully installed marionette-driver 09:56:09 INFO - Cleaning up... 09:56:09 INFO - Return code: 0 09:56:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:09 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 0x108a95c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108a99768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd598d7a880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, '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.CSaXgmznA6/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.qOqhLjOYs4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:56:09 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 09:56:09 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 09:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:09 INFO - 'HOME': '/Users/cltbld', 09:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:09 INFO - 'LOGNAME': 'cltbld', 09:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:09 INFO - 'MOZ_NO_REMOTE': '1', 09:56:09 INFO - 'NO_EM_RESTART': '1', 09:56:09 INFO - 'PAGER': '/bin/cat', 09:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:09 INFO - 'PWD': '/builds/slave/test', 09:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:09 INFO - 'SHELL': '/bin/bash', 09:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:56:09 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:09 INFO - 'USER': 'cltbld', 09:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:09 INFO - 'XPC_FLAGS': '0x0', 09:56:09 INFO - 'XPC_SERVICE_NAME': '0', 09:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:56:10 INFO - Cleaning up... 09:56:10 INFO - Return code: 0 09:56:10 INFO - Running post-action listener: _resource_record_post_action 09:56:10 INFO - Running post-action listener: _start_resource_monitoring 09:56:10 INFO - Starting resource monitoring. 09:56:10 INFO - [mozharness: 2017-06-07 16:56:10.185965Z] Finished create-virtualenv step (success) 09:56:10 INFO - [mozharness: 2017-06-07 16:56:10.186961Z] Running install step. 09:56:10 INFO - Running pre-action listener: _resource_record_pre_action 09:56:10 INFO - Running main action method: install 09:56:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:10 INFO - Reading from file tmpfile_stdout 09:56:10 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:10 INFO - Reading from file tmpfile_stdout 09:56:10 INFO - Output received: 09:56:10 INFO - Usage: mozinstall [options] installer 09:56:10 INFO - Options: 09:56:10 INFO - -h, --help show this help message and exit 09:56:10 INFO - -d DEST, --destination=DEST 09:56:10 INFO - Directory to install application into. [default: 09:56:10 INFO - "/builds/slave/test"] 09:56:10 INFO - --app=APP Application being installed. [default: firefox] 09:56:10 INFO - mkdir: /builds/slave/test/build/application 09:56:10 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'] 09:56:10 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 09:56:31 INFO - Reading from file tmpfile_stdout 09:56:31 INFO - Output received: 09:56:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:56:31 INFO - Running post-action listener: _resource_record_post_action 09:56:31 INFO - [mozharness: 2017-06-07 16:56:31.982222Z] Finished install step (success) 09:56:31 INFO - [mozharness: 2017-06-07 16:56:31.982341Z] Running setup-mitmproxy step. 09:56:31 INFO - Running pre-action listener: _resource_record_pre_action 09:56:31 INFO - Running main action method: setup_mitmproxy 09:56:31 INFO - Skipping: mitmproxy is not required 09:56:31 INFO - Running post-action listener: _resource_record_post_action 09:56:31 INFO - [mozharness: 2017-06-07 16:56:31.982951Z] Finished setup-mitmproxy step (success) 09:56:31 INFO - [mozharness: 2017-06-07 16:56:31.983036Z] Running run-tests step. 09:56:31 INFO - Running pre-action listener: _resource_record_pre_action 09:56:31 INFO - Running main action method: run_tests 09:56:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:31 INFO - 2017-06-07 09:56:31.995 screenresolution[1624:7418] starting screenresolution argv=screenresolution get 09:56:31 INFO - 2017-06-07 09:56:31.998 screenresolution[1624:7418] Display 0: 1600x1200x32@60 09:56:32 INFO - 2017-06-07 09:56:32.003 screenresolution[1625:7420] starting screenresolution argv=screenresolution list 09:56:32 INFO - Available Modes on Display 0 09:56:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:32 INFO - Intel Iris: 09:56:32 INFO - Chipset Model: Intel Iris 09:56:32 INFO - Type: GPU 09:56:32 INFO - Bus: Built-In 09:56:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:32 INFO - Vendor: Intel (0x8086) 09:56:32 INFO - Device ID: 0x0a2e 09:56:32 INFO - Revision ID: 0x0009 09:56:32 INFO - Displays: 09:56:32 INFO - Display: 09:56:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:32 INFO - Display Serial Number: 1600x1200 60 09:56:32 INFO - Main Display: Yes 09:56:32 INFO - Mirror: Off 09:56:32 INFO - Online: Yes 09:56:32 INFO - Rotation: Supported 09:56:32 INFO - Return code: 0 09:56:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:56:32 INFO - Python 2.7.3 09:56:32 INFO - Return code: 0 09:56:32 INFO - grabbing minidump binary from tooltool 09:56: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')]} 09:56:32 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 0x1087ebd30>, '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 0x1087b5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1087dcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:56:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:56:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:56:32 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 09:56:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:32 INFO - Return code: 0 09:56:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:56:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:56:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:56:32 INFO - ENV: RUST_BACKTRACE is now 1 09:56:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:56:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:56:32 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/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 09:56:32 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/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0274 --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 09:56:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CSaXgmznA6/Render', 09:56:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:32 INFO - 'HOME': '/Users/cltbld', 09:56:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:32 INFO - 'LOGNAME': 'cltbld', 09:56:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:56:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:32 INFO - 'MOZ_NO_REMOTE': '1', 09:56:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:56:32 INFO - 'NO_EM_RESTART': '1', 09:56:32 INFO - 'PAGER': '/bin/cat', 09:56:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:32 INFO - 'PWD': '/builds/slave/test', 09:56:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:56:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:32 INFO - 'RUST_BACKTRACE': '1', 09:56:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:56:32 INFO - 'SHELL': '/bin/bash', 09:56:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qOqhLjOYs4/Listeners', 09:56:32 INFO - 'TMPDIR': '/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/', 09:56:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:32 INFO - 'USER': 'cltbld', 09:56:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:32 INFO - 'XPC_FLAGS': '0x0', 09:56:32 INFO - 'XPC_SERVICE_NAME': '0', 09:56:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:32 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/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0274', '--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 09:56:32 INFO - mozversion application_buildid: 20170607084026 09:56:32 INFO - mozversion application_changeset: a49112c7a5765802096b3fc298069b9495436107 09:56:32 INFO - mozversion application_display_name: Nightly 09:56:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:32 INFO - mozversion application_name: Firefox 09:56:32 INFO - mozversion application_remotingname: firefox 09:56:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:56:32 INFO - mozversion application_vendor: Mozilla 09:56:32 INFO - mozversion application_version: 55.0a1 09:56:32 INFO - mozversion platform_buildid: 20170607084026 09:56:32 INFO - mozversion platform_changeset: a49112c7a5765802096b3fc298069b9495436107 09:56:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:56:32 INFO - mozversion platform_version: 55.0a1 09:56:32 INFO - using testdate: 1496854592 09:56:32 INFO - actual date: 1496854592 09:56:32 INFO - starting webserver on 'localhost:49228' 09:56:32 INFO - SUITE-START | Running 2 tests 09:56:32 INFO - TEST-START | dromaeo_css 09:56:32 INFO - operating with platform_type : mac_ 09:56:32 INFO - Initialising browser for dromaeo_css test... 09:56:32 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpYzBvGZ/profile 09:56:32 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 09:57:06 INFO - TEST-INFO | 1635: killed by SIGTERM 09:57:06 INFO - Browser initialized. 09:57:06 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:06 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpYzBvGZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:08 INFO - PID 1645 | 2017-06-07 09:57:08.279 plugin-container[1646:8256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 09:57:08 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:08 INFO - PID 1645 | 2017-06-07 09:57:08.280 plugin-container[1646:8256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 09:57:08 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:08 INFO - PID 1645 | 2017-06-07 09:57:08.280 plugin-container[1646:8256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:08 INFO - PID 1645 | 2017-06-07 09:57:08.981 plugin-container[1647:8301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 09:57:08 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:08 INFO - PID 1645 | 2017-06-07 09:57:08.982 plugin-container[1647:8301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 09:57:08 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:57:08 INFO - PID 1645 | 2017-06-07 09:57:08.982 plugin-container[1647:8301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:57:18 INFO - PID 1645 | RSS: Main: 252837888 09:57:18 INFO - PID 1645 | 10:00:20 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:00:22 INFO - PID 1645 | RSS: Main: 266326016 10:00:22 INFO - PID 1645 | 10:03:24 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 10:03:25 INFO - PID 1645 | RSS: Main: 278966272 10:03:25 INFO - PID 1645 | 10:06:26 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 10:06:27 INFO - PID 1645 | RSS: Main: 278519808 10:06:27 INFO - PID 1645 | 10:09:29 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 10:09:29 INFO - PID 1645 | RSS: Main: 282902528 10:09:29 INFO - PID 1645 | 10:12:32 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 10:12:33 INFO - PID 1645 | RSS: Main: 281841664 10:12:33 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 10:15:35 INFO - PID 1645 | RSS: Main: 278765568 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | __start_tp_report 10:15:35 INFO - PID 1645 | _x_x_mozilla_page_load 10:15:35 INFO - PID 1645 | _x_x_mozilla_page_load_details 10:15:35 INFO - PID 1645 | |i|pagename|runs| 10:15:35 INFO - PID 1645 | |0;dojo.html;3608.440691692788;3626;3670.155864151245;3683.079230192452;3687.391580389236;5252.212168174766;6147.954847675895;6152.323244443113;6187.814365569029;6271.560990730651;6192.907106393396;6196.969015154929;6232.875342493147;6308.211473565804;6363.109164716931;4365.869023929287;4552.226121559343;4911.698399924023;5426.077566813641;5515.61390702651;5485.177223416479;5518.862028449301;5532.031894418461;5561.304836895388;5592.384837667853;5324.893502129975;5381.67709937405;5402.243685883979;5455.399906010336;5457.808976685797;5492.148716948879;5493.917591236134;5498.890022199574;5513.393526712059;5522.088855338864;4897.510248975074;5437.592180586472;5456.91814622781;5471.644343117685;5472.616916815825;5799.811038737091;6130.27584246411;6199.070139479069;6208.068789681539;6221.284552276501;6675.532712710112;6765.120534330555;6805.0472933788815;6818.659067046678;6822.624755638438;6918.308169183092;6924.58452492847;6937.7224911003495;6945.861082778367;6952.409045231111;6282.277538083087;6288.622682638965;6299.496040316763;6359.300476947578;6412.326705695881;6999.405050570656;7063.71745130189;7064.540804847668;7068.71725130989;7095.325944035309;5516.724163791794;6500.219973603198;6590.340965903371;6624.337566243337;6726.327367263235;5762.30852297727;6736.157980252468;6840.384365407038;6865.382115609519;6877.243503214642;6545.607263564201;6655.101561289261;6744.5616034957575;6769.120014398195;6777.152855893013;6570.080188773575;6595.769148079794;6662.766803161866;6709.094272273181;6722;5410.1343784994215;5418.295621569171;5424.376196737426;5425.321834770653;5434;4799.424069111729;4809.879753006203;4826.493218212107;4854.538818812207;4887.071456423339;5294.41761406245;6285.308616052231;6338.714757835908;6364;6414.102025716404;5685.943140568624;6867.416269617039;6899.689513971884;6906;6940.583564986117;6357.96821015892;6380.776672816522;6384.07430922523;6413.807585772434;6428.164338636041;5900.43945825146;5930.318013428425;5984.910226346608;6001.459868611846;6121.877562448726;7044.36600705939;7114.57312561248;7118.039064726196;7121.857562848818;7127.358537731629;6856.760013399507;6912.688928998307;6920.134983127109;6948.270431604608;6972.9302706972285;6827.52207345481;6970.477214208853;6987.790366289019;7071.045408869737;7094.787156385317;6713.530052896445;6756.459483241427;6799.762008329685;6827.487938404739;6832.077669514552;6835.179778426422;6921.688524016331;6924.4114250287275;6949.861002779815;6961.2342642308295;6322.2729386119345;6448.29068802422;6547.1161393213215;6586.637734924625;6591.209054913441;5423.213634023124;6666.433353165035;6672.599644021374;6688.163979502562;6696.129503164558;6426.4537514311805;6522.706478208397;6537.477001839937;6542.182227221598;6570.737170513126;5470.781168753205;5519.696416696959;6156.907646385218;6508.967455162694;6649.4680425564875;6284.654344010941;6353.396427339396;6381.87236255263;6411.743530258924;6438.195225596801 10:15:35 INFO - PID 1645 | |1;ext.html;781.6990458673409;807.2049031703773;849.6516428264409;1242.1677476091013;1303.621949634606;30606.23484412888;31886;39889;39997.80001099998;40004.19991600174;40276.39585406221;40710;40745.59254407455;40783.77648670544;40785.796071019606;7774.6890124395095;7942.563159026251;8201.26188643022;8379.497230166178;8406.495610263404;8309.626066827008;8406.873896891551;8406.915930840705;8418.957905210465;8436.282915952152;8407.201315874981;8446.493210407396;8452.366072544553;8468.407211495198;8468.78828029298;8378.371622128365;8387.664493420256;8397.244248017647;8404.327653787714;8441.409101362908;8310.085890552034;8333.208345207195;8348.874766878502;8364.37267204956;8364.58177091149;38191.427128593095;38346.80826595849;38363.424548631796;38432.61567384318;38504.229915401535;3590.120420496995;3618.059304580802;3636.381815091441;3641.417373220272;3641.9453708194396;30755;30799.230019249473;31090.911818086257;31095.22261943447;31152.688473115206;2634.802389820752;2996.2659148508537;3041.2548925513393;3045.3909218156236;3051.908442746721;243559.78220108786;244289;289753.9999999979;315024;315964.84035159583;49520.75239623779;49539.50460495421;49547.76130596764;49730;49805.50194498081;50640.49359506358;50714;51097.744511277946;51689.707757306365;51873.2219016715;14447.638809029859;14464.493742719165;14587.41650333996;14653.780193297109;14662.853371466363;11606.767864642828;11609;11670.533178672928;11723.824142637868;11748.58879939198;11424.200305978502;14115.223662698445;14126.011179217356;14143.43426262938;14144.64638384028;6054.091886217015;6085.330613632497;6108.480779133734;6117.388261173936;6121.724522396503;787.3032366355816;851.6125163050797;935.3125452792192;966.1594412860856;1033.8035773203217;24190.63714044291;24678.25965221046;25028.4994300113;25060.248192554252;25329;1048.528162326956;1048.809601102754;1070.1813112968584;1080.3841810168128;1147.1511081799408;9919;10321.535530901128;10328;12113.456174203517;12922.612321630366;337099;339299;339716;339946;340464;55133;55579.722101389234;55678.44321556733;55783.88432231333;55828.16257756137;1488.7990121333698;1488.932998015092;1508.5097343363232;1522.7563589825797;1530.4719871644265;21507.139714411875;22096.00567974413;22881.771182287965;23362.883185583963;24208.75791242065;37608.811955940044;38103.42844857274;38308;38491.23017539689;38491.807540962116;270113.6494317516;270197;270820;271210;271306;35880;36400.81799590985;36530.452043219906;36661.08347291232;36835.447468288534;43474.913127172076;45743.313850291604;46131.76934115308;46151.53848461607;46408.30387544122;29313.85343073271;29946.850265748533;30025.399492010474;30686.079417617508;31510.054698359087;29849.70150298469;29990.55014174833;30315;31343.68656313499;31442.371152577278;25415.87292063528;34311.82844085764;37629.81185094057;37738;37740.62259377371;23932.162374316817;28604;28702;29224.70775292305;29881.402371952874;20548.486287842923;24550.386240344134;24733.25800225996;24826.50346993086;24979 10:15:35 INFO - PID 1645 | |2;jquery.html;426.9167512335094;436.5677978800988;437.6302024789053;446.6337603165405;450.45719907511454;14533.709325813477;15125.470608528703;19172.520686982814;22510.09959601616;22779.77220227797;22431.214907478243;23543.764562354372;23548.646770298416;23685.76314236857;23732.64401033986;5652.699879027826;6116.938830611703;6201.503879689625;6334.3032266450655;6389.424951754341;6182.072689096649;6297.023961285985;6305.590136641126;6308.211473565804;6313.621182729028;5955.659976505273;6010.96994515029;6166.475849552793;6250.656213908233;6280.591761535508;5562;6235.657038862861;6236.282827474808;6251.718672659718;6254.9374506255035;5522.3649280332875;5549.694766787825;5550.333959924795;5558.861028474321;5564.721763911788;27755.86122069397;27903.86048069767;28104;28260.85869570639;28299.85850070757;2094.7800480949586;2153.6231159547015;2185.4099393163915;2185.4864106934856;2189.861272138479;18194;18474.90762546192;18548.629027419374;18649.906750466296;18738.625227495373;1865.32848174657;2122.1405330841035;2147.0660262785736;2199.692043113965;2223.344113486525;132883.6711632881;153677.46322536742;157777;160297.19851400785;185293.07353463146;12153.939230303791;12897.35513224335;13159.53941612039;13202.07585469027;13387.933060334635;16610.916945415196;16631.002139871645;16727.749083763752;16783.580410489834;16797.832021679627;8842.646294148162;9014.909850901407;9019.909800901907;9055.773605659911;9095.36332456735;7794.610269486503;7888.881666775003;8567.74296771098;8597.398182127192;8653.524056176844;7858.035491127218;8030.959845200737;8118.878216826753;8130.268275855083;8229.300509456763;2003.2387692676689;2246.1913711064003;2260.6043942310357;2272.170657709924;2438.378213555532;924.2236521322121;931.6971984105057;949.4445749236636;972.8394814855611;994.8308787505997;21051.842148681662;21074.262400816206;21157.78842211589;21600.24399146054;21830.454238643844;930.2139691960283;986.4180133721139;987.7975015121883;1036.103770238738;1036.5646428500047;8869.600867960957;10753.032227099598;11046.061084807721;11199.328040317607;11471.426428678533;482520.76218856743;531255;535954.3202283963;575360;576759;167417.3258267426;168574.15712921356;171286;171767;172975.13512432357;1158.9130815188726;1210.4371467267588;1225.5771758743397;1236.771197328497;1238.2694210415898;21275.255366062112;21328.720276783442;24039.27882163538;24056.51886962216;24284;71362.6431867858;71514.02763653632;72067;72205;73177;97086;97392;97778;97968;98352;13702.314884255948;16099.114548699465;16545.91727041357;16569.91715041417;16582.66834663324;18191.09044547741;18279;18440.81559184391;18527;18632.534186645444;14746.926265369033;14987.475438359605;15200.239988000334;15256.23718814077;15313.770293445385;12923.61229163089;13015.479380824661;13050.608481745563;13357.065005449525;13629.522966696119;10755.677329680122;15260.931734778458;15417.229138542803;15539.989900656421;15654.45209417665;16520.917395412947;16850.66298674044;16864.494065178063;16978;16984.57538561546;14839.777403338743;15051.548453546411;15121.243937803285;15168.848311517186;15257.465988690343 10:15:35 INFO - PID 1645 | |3;mootools.html;1428.7285415771003;1465.904716193448;1488.0476495043174;1497.7603583426653;1508.0951429142513;10312;10562.155027597793;11805.173637845344;12095;12323.753524929496;11930.403479826018;12205.57280495183;12250.387480625977;12258.264504129736;12281.201721888072;2443.2548072837735;2506.5989441689335;2510.899564017437;2512.4095837478176;2519.0301733832516;2132.616129096762;2240.843140980132;2364.716234051916;2392.449736560592;2489.6887889013874;2194.5501172259646;2516.7986561075163;2615.3592369869407;2615.5292047431453;2615.856127912964;2559.6416501689687;2560.4495033567873;2566.4353842154696;2570.3574106473384;2576.5233431815172;2529.519391315644;2544.3384719972755;2544.745525447459;2547.9108231212003;2551.7065537463245;11891.167618266725;11938.985186259179;12026.218295810744;12048;12143.635690929286;6493.70778314977;6746.932530674729;6749.392554670054;6764.019217213526;6777.390034896834;3373.780704254216;4232.238197124517;4260.446142001551;4280.871573852789;4316.676249281285;1221.908356873238;1234.2224398628807;1239.1202246405064;1261.4323554400557;1263.298869127634;16889.408870689465;16901.57746056346;16905.40831070919;16915.577110572212;16974.830251697447;5943.851403714942;6009.489193418521;6025.578209525379;6155.876882462326;6164.815055548341;13960.232187229596;13974.510892118727;14153.363098660586;14158.716825663429;14169;6686.1642294713165;6719.160104986877;6742.056112144303;6743.325667433218;6746.966265168741;5975.462208401264;6069.939300607026;6070.7875224366935;6119.632822030603;6197.690115494297;4158.625723684883;4666.859994200179;4705.1765940960195;4760.547747963938;4803;1280.475005247844;1326.383231797219;1422.3101795629111;1429.9356528956223;1454.2147240490228;804.6499772598938;855.7860534866284;857.5583574459158;864.4856310495245;870.1168314161132;5662.57530685192;7016.894746578805;7288.132210291053;7299.927000730031;7320.194778574352;800.3717082090648;986.7286496213455;992.9602815887428;992.9950350248203;995.4077323992213;4644.117617652635;4721.7403042832975;4863.1732605457155;4967.205247160509;4978.800847966041;14751.483698070731;15070.171140587154;15152.772708409382;15598.610034748996;15642.68714625701;15199.848001520064;15453.227338633249;15457.613559661098;15538;15558.22208889551;8492.830143397221;8714.651413943498;8732.170443807829;8793.20861122502;8799.12008799115;6992.1958974718555;7271.091113610799;7311.670974806253;7494.475386722877;7826.295633393022;13485.797713034117;13782.379792909143;13946.30268486592;13982.510612128526;14023.298835058411;13906.404917707634;14069.296535172996;14209.076410033314;14241.928790355982;14295.28523573838;8312.5428101455;8834.734957951272;9922.900770992486;9926;9931.801363972536;10842.457877106272;10940.890591093987;11006.559737610727;11043.834342484708;11066.170037247077;8735.344849136212;9434.811303774024;9437.528123593656;9472.431654100776;9481.09929556691;7567.886481702669;9200.447973121634;9236.676716314896;9272.860907086264;9308.394954327947;8296.377771667028;8342.833143336979;8429;8430.15698430152;8437.029741579798;6062.393760623902;6292.496600271876;6351.2696039956;6374.74501019954;6512.902306465501;6273.404026617464;6286.591371560834;6494.740210391531;6536.967315163393;6553.574017689026 10:15:35 INFO - PID 1645 | |4;prototype.html;394.05230420837876;403.8970062634026;404.2945060868784;412.8472465187881;418.20958388645465;6336.619802811834;6407.231132264134;6586.571872828269;6611.008348747684;6665.300143484937;4930.901381972359;5332.92000619991;6228.688565571726;6234.877722010036;6326.255308518611;3448.637893021234;4527.3888025116685;4668.976655116737;4677.812887484497;4687.086018226447;4689.742064186468;4690.97654511727;4703.553162449562;4704.317873908281;4707.411573553306;3831.7163750143595;4597.379353787246;4605.953940460586;4616.376789133457;4624.445066592014;3900.7074469414624;4049.666856463585;4175.665946724254;4194.475690538683;4615.26155815068;3821.159344944108;3997.560268370477;4004.2592120457807;4015.156817068419;4020.959790402117;5186.377634683862;6128.804883047763;6314.368563143695;6316.74733010679;6336.429721325057;4949.950500494985;5293.655912365684;5514.283143191399;5550.611457197998;5584.078627026536;6865;6868.862622747567;6871.793846184622;6878.312168783132;6900.930990690129;4016.297147999118;4149.232392007489;4564.406627138484;4584.885377865547;4588.24293991491;6345.96827015862;6822.3859852613505;7013.473989450812;7178.856422871513;7250.918359697161;4595.1728688835665;4643.4660014098135;4653.230882984725;4657.67396282257;4658.231391820379;4723.834665786681;4726.95273047272;4741.193997020515;4752.572268495852;4761.52384761528;5050.797968081309;5061.392632884077;5535.474129957647;5535.474129957647;5546.168074788814;6860.725570977205;6876.17485901685;6876.553024053421;6905.75829845953;6907.20567134786;1410.8448070712213;1431.5347512058622;1530.7703844423204;1557.0035177486418;1635.378556148656;1036.8859425463193;1066.6027503969824;1069.1660504806264;1071.4321409652741;1076.1283360478037;5710.229119068873;6496.967515162489;6498.285188629247;6499.155109835691;6523.249826269945;5817.185594016841;6508.511861610398;6533.24867640218;7131.322524360177;7155.248698886646;6448.677566121769;6458.192725909262;6487.734891696074;6490.740370385226;6555.968079630992;3269.8692052318115;3396.4735466002808;3700.796456194881;3735.869244576481;3757;6235.96882015587;7355.448341374418;7391.445641576904;7406.185319614838;7432.219616940251;7364.3003914628025;7365.263473652699;7388.261173882676;7390.22402647725;7397.556146631219;6492.532837521711;7092.716291348288;7293.52592081513;7371.299726525972;7409.629518524052;7108.609026503592;7136.85726285461;7148.106486689164;7154.964225178841;7160.890062040392;6438.197304754676;6601.207855057424;7039.225685174729;7048.894266585903;7054.224035356004;7390.224026477143;7396.564988460074;7418.814529636802;7420.332170104501;7450.701971921062;4644;4663.580277775017;4686.625069994324;4688.953110468851;4697.271922851964;6004.3280740072;7033.613151276729;7161.4986950912935;7203.747868824566;7211.783646490614;5548.805791797267;6796.89296318143;6906.37842594169;6936.127495292778;6963.09479767627;6213.937860621517;6326.493880489457;6335.366463353812;6895.793126206222;6918.757843475454;5819.679917604572;6154.569180157346;6712.731490740316;6780.288069752605;6801.965990170018;6560.803175904731;6619.702113404812;6939.583624982517;6947.861042779015;6985.545939514119;6733;6746.156730408699;6751.797446076625;6762.797116086525;6855.177378714387 10:15:35 INFO - PID 1645 | |5;yui.html;705.6577559883456;745.9142198647155;818.7134502923981;847.0724556610511;862.1077185113409;14336.64158396038;14943.252837358115;16762.74855877163;17166.570835729097;17274.740878886827;16162.030278183318;17716.911415442904;17968.55078623035;18027.999999999967;18193.54516137094;976.72651657536;998.5456617239139;1004.1615251265202;1024.0834453164414;1024.5799222318851;982.641335912394;1036.7097212780434;1037.9065884070433;1038.70396936873;1038.8805287391972;954.3319676226669;1063.5958335832372;1071.3464786480279;1072.5656109275717;1072.908802751767;1060.464465444953;1062.3785085724821;1065.0148612533376;1067.3809190669392;1070.8661417322835;774.6939958716275;775.1202385292734;775.9495632783908;778.9454738168331;794.241499368102;17256.137193140294;17513.824861751473;17599.912000440043;17651.558711032198;17809.287628494843;17731.468055958212;17824;17892.55268618282;17977.73033404487;18293.90853045726;14645.707085858223;14932.402703891832;15015.474458394014;15025;15068.54794356171;3593.2274560969518;3604.3692353838237;3631.400818864884;3645.6354364563595;3663.7984910829896;149886.25056874755;151589.24205378903;153171.46828531683;153887.46112538956;155886;13608.795868061788;13698.041137120306;13787.034907556375;13800.723985520233;13949.58151255464;26188.214353569423;26429.33926651849;26500.734992650214;26682.332941676224;26931.057412990453;7674.194209608022;7838.176991415819;7906.604669766489;8094.676212951416;8144.837103257902;3934.075492259374;3982.880513584597;4012.959870401317;4019.0756126091164;4020.1758639478835;4311.374850646639;4416.028473735773;4476.843310484118;4522.570355816192;4526.298423744325;870.8026463612533;947.1286416496839;957.0238356875948;969.4813274897896;981.7103954333488;637.5855693799068;643.7489379142138;648.4164252173082;654.9214094308618;671.2549070531705;11379.829302560469;11747.706307342385;12378.75242495145;12957.611271661865;13073.15024523422;643.7264162730914;649.5940037476577;657.3525077798365;661.147120214927;670.5574320948248;5295.735213239323;5739.368669446358;5897.823065308047;6514.73941042353;6714.697838597275;137570;138534.92197617044;140483;140706.29646851702;141254;119129;121861.78138218682;123247;124703;125904.74095259114;106987.4650626742;109359;111984;114118;114565;12580.685482862635;12712.491500340253;13212.801807973081;13244.602661920158;13279.80080298777;37890.24219515539;44312.4490642826;45841.77079114583;46228.768856155504;46356.841078973295;60138.699306503186;62101.75796484135;62316.75366492735;62447.375526244155;62788.744225116156;16220.594485137966;17228.224729886933;17608.207630656394;17787.46637600874;17796.199171037075;15999.600009999844;18456.815431846047;18647;18922.810771892655;19134.330298439487;17376.478705638852;17754.467365979042;18021.819781802013;18106.637867242844;18180.909095454437;17057.658846823244;17456.73814892752;17804;17958.371456998942;17988.730169047212;17446.214920328362;17491.47525574235;17505.124743762506;17762.733558996366;17860.821391785918;11822;11826.349550774446;11944.761104777683;12357.691057723629;12442.129050966345;11956.175298804781;12937.353132343533;13040.934795325962;13664.93167534156;13676 10:15:35 INFO - PID 1645 | __end_tp_report 10:15:35 INFO - PID 1645 | __start_cc_report 10:15:35 INFO - PID 1645 | _x_x_mozilla_cycle_collect,450 10:15:35 INFO - PID 1645 | __end_cc_report 10:15:35 INFO - PID 1645 | __startTimestamp1496855735388__endTimestamp 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | ------- Summary: start ------- 10:15:35 INFO - PID 1645 | Number of tests: 6 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6190.22 Median:6411.74 stddev:787.06 (12.3%) stddev-sans-first:762.97 10:15:35 INFO - PID 1645 | Values: 3608.4 3626.0 3670.2 3683.1 3687.4 5252.2 6148.0 6152.3 6187.8 6271.6 6192.9 6197.0 6232.9 6308.2 6363.1 4365.9 4552.2 4911.7 5426.1 5515.6 5485.2 5518.9 5532.0 5561.3 5592.4 5324.9 5381.7 5402.2 5455.4 5457.8 5492.1 5493.9 5498.9 5513.4 5522.1 4897.5 5437.6 5456.9 5471.6 5472.6 5799.8 6130.3 6199.1 6208.1 6221.3 6675.5 6765.1 6805.0 6818.7 6822.6 6918.3 6924.6 6937.7 6945.9 6952.4 6282.3 6288.6 6299.5 6359.3 6412.3 6999.4 7063.7 7064.5 7068.7 7095.3 5516.7 6500.2 6590.3 6624.3 6726.3 5762.3 6736.2 6840.4 6865.4 6877.2 6545.6 6655.1 6744.6 6769.1 6777.2 6570.1 6595.8 6662.8 6709.1 6722.0 5410.1 5418.3 5424.4 5425.3 5434.0 4799.4 4809.9 4826.5 4854.5 4887.1 5294.4 6285.3 6338.7 6364.0 6414.1 5685.9 6867.4 6899.7 6906.0 6940.6 6358.0 6380.8 6384.1 6413.8 6428.2 5900.4 5930.3 5984.9 6001.5 6121.9 7044.4 7114.6 7118.0 7121.9 7127.4 6856.8 6912.7 6920.1 6948.3 6972.9 6827.5 6970.5 6987.8 7071.0 7094.8 6713.5 6756.5 6799.8 6827.5 6832.1 6835.2 6921.7 6924.4 6949.9 6961.2 6322.3 6448.3 6547.1 6586.6 6591.2 5423.2 6666.4 6672.6 6688.2 6696.1 6426.5 6522.7 6537.5 6542.2 6570.7 5470.8 5519.7 6156.9 6509.0 6649.5 6284.7 6353.4 6381.9 6411.7 6438.2 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:44996.74 Median:35095.91 stddev:78582.03 (223.9%) stddev-sans-first:78732.35 10:15:35 INFO - PID 1645 | Values: 781.7 807.2 849.7 1242.2 1303.6 30606.2 31886.0 39889.0 39997.8 40004.2 40276.4 40710.0 40745.6 40783.8 40785.8 7774.7 7942.6 8201.3 8379.5 8406.5 8309.6 8406.9 8406.9 8419.0 8436.3 8407.2 8446.5 8452.4 8468.4 8468.8 8378.4 8387.7 8397.2 8404.3 8441.4 8310.1 8333.2 8348.9 8364.4 8364.6 38191.4 38346.8 38363.4 38432.6 38504.2 3590.1 3618.1 3636.4 3641.4 3641.9 30755.0 30799.2 31090.9 31095.2 31152.7 2634.8 2996.3 3041.3 3045.4 3051.9 243559.8 244289.0 289754.0 315024.0 315964.8 49520.8 49539.5 49547.8 49730.0 49805.5 50640.5 50714.0 51097.7 51689.7 51873.2 14447.6 14464.5 14587.4 14653.8 14662.9 11606.8 11609.0 11670.5 11723.8 11748.6 11424.2 14115.2 14126.0 14143.4 14144.6 6054.1 6085.3 6108.5 6117.4 6121.7 787.3 851.6 935.3 966.2 1033.8 24190.6 24678.3 25028.5 25060.2 25329.0 1048.5 1048.8 1070.2 1080.4 1147.2 9919.0 10321.5 10328.0 12113.5 12922.6 337099.0 339299.0 339716.0 339946.0 340464.0 55133.0 55579.7 55678.4 55783.9 55828.2 1488.8 1488.9 1508.5 1522.8 1530.5 21507.1 22096.0 22881.8 23362.9 24208.8 37608.8 38103.4 38308.0 38491.2 38491.8 270113.6 270197.0 270820.0 271210.0 271306.0 35880.0 36400.8 36530.5 36661.1 36835.4 43474.9 45743.3 46131.8 46151.5 46408.3 29313.9 29946.9 30025.4 30686.1 31510.1 29849.7 29990.6 30315.0 31343.7 31442.4 25415.9 34311.8 37629.8 37738.0 37740.6 23932.2 28604.0 28702.0 29224.7 29881.4 20548.5 24550.4 24733.3 24826.5 24979.0 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:38521.98 Median:2211.52 stddev:93732.51 (4238.4%) stddev-sans-first:93951.82 10:15:35 INFO - PID 1645 | Values: 426.9 436.6 437.6 446.6 450.5 14533.7 15125.5 19172.5 22510.1 22779.8 22431.2 23543.8 23548.6 23685.8 23732.6 5652.7 6116.9 6201.5 6334.3 6389.4 6182.1 6297.0 6305.6 6308.2 6313.6 5955.7 6011.0 6166.5 6250.7 6280.6 5562.0 6235.7 6236.3 6251.7 6254.9 5522.4 5549.7 5550.3 5558.9 5564.7 27755.9 27903.9 28104.0 28260.9 28299.9 2094.8 2153.6 2185.4 2185.5 2189.9 18194.0 18474.9 18548.6 18649.9 18738.6 1865.3 2122.1 2147.1 2199.7 2223.3 132883.7 153677.5 157777.0 160297.2 185293.1 12153.9 12897.4 13159.5 13202.1 13387.9 16610.9 16631.0 16727.7 16783.6 16797.8 8842.6 9014.9 9019.9 9055.8 9095.4 7794.6 7888.9 8567.7 8597.4 8653.5 7858.0 8031.0 8118.9 8130.3 8229.3 2003.2 2246.2 2260.6 2272.2 2438.4 924.2 931.7 949.4 972.8 994.8 21051.8 21074.3 21157.8 21600.2 21830.5 930.2 986.4 987.8 1036.1 1036.6 8869.6 10753.0 11046.1 11199.3 11471.4 482520.8 531255.0 535954.3 575360.0 576759.0 167417.3 168574.2 171286.0 171767.0 172975.1 1158.9 1210.4 1225.6 1236.8 1238.3 21275.3 21328.7 24039.3 24056.5 24284.0 71362.6 71514.0 72067.0 72205.0 73177.0 97086.0 97392.0 97778.0 97968.0 98352.0 13702.3 16099.1 16545.9 16569.9 16582.7 18191.1 18279.0 18440.8 18527.0 18632.5 14746.9 14987.5 15200.2 15256.2 15313.8 12923.6 13015.5 13050.6 13357.1 13629.5 10755.7 15260.9 15417.2 15540.0 15654.5 16520.9 16850.7 16864.5 16978.0 16984.6 14839.8 15051.5 15121.2 15168.8 15257.5 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:7375.03 Median:3766.20 stddev:4677.17 (124.2%) stddev-sans-first:4668.94 10:15:35 INFO - PID 1645 | Values: 1428.7 1465.9 1488.0 1497.8 1508.1 10312.0 10562.2 11805.2 12095.0 12323.8 11930.4 12205.6 12250.4 12258.3 12281.2 2443.3 2506.6 2510.9 2512.4 2519.0 2132.6 2240.8 2364.7 2392.4 2489.7 2194.6 2516.8 2615.4 2615.5 2615.9 2559.6 2560.4 2566.4 2570.4 2576.5 2529.5 2544.3 2544.7 2547.9 2551.7 11891.2 11939.0 12026.2 12048.0 12143.6 6493.7 6746.9 6749.4 6764.0 6777.4 3373.8 4232.2 4260.4 4280.9 4316.7 1221.9 1234.2 1239.1 1261.4 1263.3 16889.4 16901.6 16905.4 16915.6 16974.8 5943.9 6009.5 6025.6 6155.9 6164.8 13960.2 13974.5 14153.4 14158.7 14169.0 6686.2 6719.2 6742.1 6743.3 6747.0 5975.5 6069.9 6070.8 6119.6 6197.7 4158.6 4666.9 4705.2 4760.5 4803.0 1280.5 1326.4 1422.3 1429.9 1454.2 804.6 855.8 857.6 864.5 870.1 5662.6 7016.9 7288.1 7299.9 7320.2 800.4 986.7 993.0 993.0 995.4 4644.1 4721.7 4863.2 4967.2 4978.8 14751.5 15070.2 15152.8 15598.6 15642.7 15199.8 15453.2 15457.6 15538.0 15558.2 8492.8 8714.7 8732.2 8793.2 8799.1 6992.2 7271.1 7311.7 7494.5 7826.3 13485.8 13782.4 13946.3 13982.5 14023.3 13906.4 14069.3 14209.1 14241.9 14295.3 8312.5 8834.7 9922.9 9926.0 9931.8 10842.5 10940.9 11006.6 11043.8 11066.2 8735.3 9434.8 9437.5 9472.4 9481.1 7567.9 9200.4 9236.7 9272.9 9308.4 8296.4 8342.8 8429.0 8430.2 8437.0 6062.4 6292.5 6351.3 6374.7 6512.9 6273.4 6286.6 6494.7 6537.0 6553.6 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5532.44 Median:6321.50 stddev:1786.55 (28.3%) stddev-sans-first:1749.44 10:15:35 INFO - PID 1645 | Values: 394.1 403.9 404.3 412.8 418.2 6336.6 6407.2 6586.6 6611.0 6665.3 4930.9 5332.9 6228.7 6234.9 6326.3 3448.6 4527.4 4669.0 4677.8 4687.1 4689.7 4691.0 4703.6 4704.3 4707.4 3831.7 4597.4 4606.0 4616.4 4624.4 3900.7 4049.7 4175.7 4194.5 4615.3 3821.2 3997.6 4004.3 4015.2 4021.0 5186.4 6128.8 6314.4 6316.7 6336.4 4950.0 5293.7 5514.3 5550.6 5584.1 6865.0 6868.9 6871.8 6878.3 6900.9 4016.3 4149.2 4564.4 4584.9 4588.2 6346.0 6822.4 7013.5 7178.9 7250.9 4595.2 4643.5 4653.2 4657.7 4658.2 4723.8 4727.0 4741.2 4752.6 4761.5 5050.8 5061.4 5535.5 5535.5 5546.2 6860.7 6876.2 6876.6 6905.8 6907.2 1410.8 1431.5 1530.8 1557.0 1635.4 1036.9 1066.6 1069.2 1071.4 1076.1 5710.2 6497.0 6498.3 6499.2 6523.2 5817.2 6508.5 6533.2 7131.3 7155.2 6448.7 6458.2 6487.7 6490.7 6556.0 3269.9 3396.5 3700.8 3735.9 3757.0 6236.0 7355.4 7391.4 7406.2 7432.2 7364.3 7365.3 7388.3 7390.2 7397.6 6492.5 7092.7 7293.5 7371.3 7409.6 7108.6 7136.9 7148.1 7155.0 7160.9 6438.2 6601.2 7039.2 7048.9 7054.2 7390.2 7396.6 7418.8 7420.3 7450.7 4644.0 4663.6 4686.6 4689.0 4697.3 6004.3 7033.6 7161.5 7203.7 7211.8 5548.8 6796.9 6906.4 6936.1 6963.1 6213.9 6326.5 6335.4 6895.8 6918.8 5819.7 6154.6 6712.7 6780.3 6802.0 6560.8 6619.7 6939.6 6947.9 6985.5 6733.0 6746.2 6751.8 6762.8 6855.2 10:15:35 INFO - PID 1645 | 10:15:35 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:25852.64 Median:17791.83 stddev:39856.63 (224.0%) stddev-sans-first:39923.72 10:15:35 INFO - PID 1645 | Values: 705.7 745.9 818.7 847.1 862.1 14336.6 14943.3 16762.7 17166.6 17274.7 16162.0 17716.9 17968.6 18028.0 18193.5 976.7 998.5 1004.2 1024.1 1024.6 982.6 1036.7 1037.9 1038.7 1038.9 954.3 1063.6 1071.3 1072.6 1072.9 1060.5 1062.4 1065.0 1067.4 1070.9 774.7 775.1 775.9 778.9 794.2 17256.1 17513.8 17599.9 17651.6 17809.3 17731.5 17824.0 17892.6 17977.7 18293.9 14645.7 14932.4 15015.5 15025.0 15068.5 3593.2 3604.4 3631.4 3645.6 3663.8 149886.3 151589.2 153171.5 153887.5 155886.0 13608.8 13698.0 13787.0 13800.7 13949.6 26188.2 26429.3 26500.7 26682.3 26931.1 7674.2 7838.2 7906.6 8094.7 8144.8 3934.1 3982.9 4013.0 4019.1 4020.2 4311.4 4416.0 4476.8 4522.6 4526.3 870.8 947.1 957.0 969.5 981.7 637.6 643.7 648.4 654.9 671.3 11379.8 11747.7 12378.8 12957.6 13073.2 643.7 649.6 657.4 661.1 670.6 5295.7 5739.4 5897.8 6514.7 6714.7 137570.0 138534.9 140483.0 140706.3 141254.0 119129.0 121861.8 123247.0 124703.0 125904.7 106987.5 109359.0 111984.0 114118.0 114565.0 12580.7 12712.5 13212.8 13244.6 13279.8 37890.2 44312.4 45841.8 46228.8 46356.8 60138.7 62101.8 62316.8 62447.4 62788.7 16220.6 17228.2 17608.2 17787.5 17796.2 15999.6 18456.8 18647.0 18922.8 19134.3 17376.5 17754.5 18021.8 18106.6 18180.9 17057.7 17456.7 17804.0 17958.4 17988.7 17446.2 17491.5 17505.1 17762.7 17860.8 11822.0 11826.3 11944.8 12357.7 12442.1 11956.2 12937.4 13040.9 13664.9 13676.0 10:15:35 INFO - PID 1645 | -------- Summary: end -------- 10:15:35 INFO - PID 1645 | 10:15:36 INFO - TEST-INFO | 1645: exit 0 10:15:36 INFO - TEST-OK | dromaeo_css | took 1143409ms 10:15:36 INFO - TEST-START | kraken 10:15:36 INFO - operating with platform_type : mac_ 10:15:36 INFO - Initialising browser for kraken test... 10:15:36 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpE8yYot/profile 10:15:36 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:16:10 INFO - TEST-INFO | 1655: killed by SIGTERM 10:16:10 INFO - Browser initialized. 10:16:10 INFO - Running cycle 1/1 for kraken test... 10:16:10 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/T/tmpE8yYot/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:12 INFO - PID 1659 | 2017-06-07 10:16:12.033 plugin-container[1660:10963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 10:16:12 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:12 INFO - PID 1659 | 2017-06-07 10:16:12.034 plugin-container[1660:10963] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa103, name = 'com.apple.CFPasteboardClient' 10:16:12 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:12 INFO - PID 1659 | 2017-06-07 10:16:12.034 plugin-container[1660:10963] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:16:12 INFO - PID 1659 | 2017-06-07 10:16:12.810 plugin-container[1661:11011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 10:16:12 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:12 INFO - PID 1659 | 2017-06-07 10:16:12.811 plugin-container[1661:11011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 10:16:12 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:12 INFO - PID 1659 | 2017-06-07 10:16:12.811 plugin-container[1661:11011] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:16:22 INFO - PID 1659 | RSS: Main: 252952576 10:16:22 INFO - PID 1659 | 10:16:24 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 10:16:25 INFO - PID 1659 | RSS: Main: 265334784 10:16:25 INFO - PID 1659 | 10:16:28 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 10:16:28 INFO - PID 1659 | RSS: Main: 274075648 10:16:28 INFO - PID 1659 | 10:16:32 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 10:16:33 INFO - PID 1659 | RSS: Main: 277057536 10:16:33 INFO - PID 1659 | 10:16:35 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 10:16:35 INFO - PID 1659 | RSS: Main: 278056960 10:16:35 INFO - PID 1659 | 10:16:37 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:16:38 INFO - PID 1659 | RSS: Main: 278822912 10:16:38 INFO - PID 1659 | 10:16:41 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:41 INFO - PID 1659 | RSS: Main: 283033600 10:16:41 INFO - PID 1659 | 10:16:45 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:45 INFO - PID 1659 | RSS: Main: 286261248 10:16:45 INFO - PID 1659 | 10:16:48 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 10:16:49 INFO - PID 1659 | RSS: Main: 284946432 10:16:49 INFO - PID 1659 | 10:16:50 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:51 INFO - PID 1659 | RSS: Main: 285106176 10:16:51 INFO - PID 1659 | 10:16:53 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:54 INFO - PID 1659 | RSS: Main: 285163520 10:16:54 INFO - PID 1659 | 10:16:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:57 INFO - PID 1659 | RSS: Main: 285343744 10:16:57 INFO - PID 1659 | 10:16:59 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:17:00 INFO - PID 1659 | RSS: Main: 284946432 10:17:00 INFO - PID 1659 | 10:17:03 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:17:04 INFO - PID 1659 | RSS: Main: 284651520 10:17:04 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 10:17:06 INFO - PID 1659 | RSS: Main: 287006720 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | __start_tp_report 10:17:06 INFO - PID 1659 | _x_x_mozilla_page_load 10:17:06 INFO - PID 1659 | _x_x_mozilla_page_load_details 10:17:06 INFO - PID 1659 | |i|pagename|runs| 10:17:06 INFO - PID 1659 | |0;ai-astar;107;118;116;114;115;116;116;116;115;113 10:17:06 INFO - PID 1659 | |1;audio-beat-detection;132;108;109;142;152;148;110;112;134;109 10:17:06 INFO - PID 1659 | |2;audio-dft;233;243;236;257;220;224;276;278;191;196 10:17:06 INFO - PID 1659 | |3;audio-fft;75;62;61;84;63;64;90;50;80;82 10:17:06 INFO - PID 1659 | |4;audio-oscillator;85;67;93;72;71;70;86;68;67;88 10:17:06 INFO - PID 1659 | |5;imaging-gaussian-blur;95;89;90;89;90;133;97;90;90;90 10:17:06 INFO - PID 1659 | |6;imaging-darkroom;97;99;97;97;98;128;103;152;140;95 10:17:06 INFO - PID 1659 | |7;imaging-desaturate;93;95;93;92;95;92;96;92;92;93 10:17:06 INFO - PID 1659 | |8;json-parse-financial;51;51;47;73;82;78;77;79;50;50 10:17:06 INFO - PID 1659 | |9;json-stringify-tinderbox;55;66;60;51;69;86;87;84;82;62 10:17:06 INFO - PID 1659 | |10;stanford-crypto-aes;87;83;81;85;85;87;86;89;113;91 10:17:06 INFO - PID 1659 | |11;stanford-crypto-ccm;144;134;135;156;155;150;128;145;138;152 10:17:06 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;179;185;196;231;202;177;225;232;223;178 10:17:06 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;68;68;64;70;69;67;67;70;65;68 10:17:06 INFO - PID 1659 | __end_tp_report 10:17:06 INFO - PID 1659 | __start_cc_report 10:17:06 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1182 10:17:06 INFO - PID 1659 | __end_cc_report 10:17:06 INFO - PID 1659 | __startTimestamp1496855826410__endTimestamp 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | ------- Summary: start ------- 10:17:06 INFO - PID 1659 | Number of tests: 14 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.00 stddev:2.99 (2.6%) stddev-sans-first:1.42 10:17:06 INFO - PID 1659 | Values: 107.0 118.0 116.0 114.0 115.0 116.0 116.0 116.0 115.0 113.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:133.00 stddev:17.85 (13.4%) stddev-sans-first:18.78 10:17:06 INFO - PID 1659 | Values: 132.0 108.0 109.0 142.0 152.0 148.0 110.0 112.0 134.0 109.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:235.40 Median:239.50 stddev:29.60 (12.4%) stddev-sans-first:31.38 10:17:06 INFO - PID 1659 | Values: 233.0 243.0 236.0 257.0 220.0 224.0 276.0 278.0 191.0 196.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:71.10 Median:77.50 stddev:12.84 (16.6%) stddev-sans-first:13.54 10:17:06 INFO - PID 1659 | Values: 75.0 62.0 61.0 84.0 63.0 64.0 90.0 50.0 80.0 82.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:78.50 stddev:10.07 (12.8%) stddev-sans-first:10.22 10:17:06 INFO - PID 1659 | Values: 85.0 67.0 93.0 72.0 71.0 70.0 86.0 68.0 67.0 88.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:90.00 stddev:13.52 (15.0%) stddev-sans-first:14.34 10:17:06 INFO - PID 1659 | Values: 95.0 89.0 90.0 89.0 90.0 133.0 97.0 90.0 90.0 90.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:97.00 stddev:21.16 (21.8%) stddev-sans-first:21.87 10:17:06 INFO - PID 1659 | Values: 97.0 99.0 97.0 97.0 98.0 128.0 103.0 152.0 140.0 95.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:93.00 stddev:1.49 (1.6%) stddev-sans-first:1.58 10:17:06 INFO - PID 1659 | Values: 93.0 95.0 93.0 92.0 95.0 92.0 96.0 92.0 92.0 93.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:75.00 stddev:14.96 (19.9%) stddev-sans-first:15.13 10:17:06 INFO - PID 1659 | Values: 51.0 51.0 47.0 73.0 82.0 78.0 77.0 79.0 50.0 50.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:75.50 stddev:13.55 (17.9%) stddev-sans-first:13.20 10:17:06 INFO - PID 1659 | Values: 55.0 66.0 60.0 51.0 69.0 86.0 87.0 84.0 82.0 62.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:86.50 stddev:8.99 (10.4%) stddev-sans-first:9.52 10:17:06 INFO - PID 1659 | Values: 87.0 83.0 81.0 85.0 85.0 87.0 86.0 89.0 113.0 91.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:147.50 stddev:9.65 (6.5%) stddev-sans-first:10.23 10:17:06 INFO - PID 1659 | Values: 144.0 134.0 135.0 156.0 155.0 150.0 128.0 145.0 138.0 152.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.80 Median:212.50 stddev:23.00 (10.8%) stddev-sans-first:22.72 10:17:06 INFO - PID 1659 | Values: 179.0 185.0 196.0 231.0 202.0 177.0 225.0 232.0 223.0 178.0 10:17:06 INFO - PID 1659 | 10:17:06 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.96 (2.9%) stddev-sans-first:2.07 10:17:06 INFO - PID 1659 | Values: 68.0 68.0 64.0 70.0 69.0 67.0 67.0 70.0 65.0 68.0 10:17:06 INFO - PID 1659 | -------- Summary: end -------- 10:17:06 INFO - PID 1659 | 10:17:07 INFO - TEST-INFO | 1659: exit 0 10:17:07 INFO - TEST-OK | kraken | took 91219ms 10:17:07 INFO - SUITE-END | took 1234s 10:17:07 INFO - Completed test suite (00:20:35) 10:17:07 INFO - javascript benchmark 10:17:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8196.575113552988, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3608.440691692788, 3626.0, 3670.155864151245, 3683.079230192452, 3687.391580389236, 5252.212168174766, 6147.954847675895, 6152.323244443113, 6187.814365569029, 6271.560990730651, 6192.907106393396, 6196.969015154929, 6232.875342493147, 6308.211473565804, 6363.109164716931, 4365.869023929287, 4552.226121559343, 4911.698399924023, 5426.077566813641, 5515.61390702651, 5485.177223416479, 5518.862028449301, 5532.031894418461, 5561.304836895388, 5592.384837667853, 5324.893502129975, 5381.67709937405, 5402.243685883979, 5455.399906010336, 5457.808976685797, 5492.148716948879, 5493.917591236134, 5498.890022199574, 5513.393526712059, 5522.088855338864, 4897.510248975074, 5437.592180586472, 5456.91814622781, 5471.644343117685, 5472.616916815825, 5799.811038737091, 6130.27584246411, 6199.070139479069, 6208.068789681539, 6221.284552276501, 6675.532712710112, 6765.120534330555, 6805.0472933788815, 6818.659067046678, 6822.624755638438, 6918.308169183092, 6924.58452492847, 6937.7224911003495, 6945.861082778367, 6952.409045231111, 6282.277538083087, 6288.622682638965, 6299.496040316763, 6359.300476947578, 6412.326705695881, 6999.405050570656, 7063.71745130189, 7064.540804847668, 7068.71725130989, 7095.325944035309, 5516.724163791794, 6500.219973603198, 6590.340965903371, 6624.337566243337, 6726.327367263235, 5762.30852297727, 6736.157980252468, 6840.384365407038, 6865.382115609519, 6877.243503214642, 6545.607263564201, 6655.101561289261, 6744.5616034957575, 6769.120014398195, 6777.152855893013, 6570.080188773575, 6595.769148079794, 6662.766803161866, 6709.094272273181, 6722.0, 5410.1343784994215, 5418.295621569171, 5424.376196737426, 5425.321834770653, 5434.0, 4799.424069111729, 4809.879753006203, 4826.493218212107, 4854.538818812207, 4887.071456423339, 5294.41761406245, 6285.308616052231, 6338.714757835908, 6364.0, 6414.102025716404, 5685.943140568624, 6867.416269617039, 6899.689513971884, 6906.0, 6940.583564986117, 6357.96821015892, 6380.776672816522, 6384.07430922523, 6413.807585772434, 6428.164338636041, 5900.43945825146, 5930.318013428425, 5984.910226346608, 6001.459868611846, 6121.877562448726, 7044.36600705939, 7114.57312561248, 7118.039064726196, 7121.857562848818, 7127.358537731629, 6856.760013399507, 6912.688928998307, 6920.134983127109, 6948.270431604608, 6972.9302706972285, 6827.52207345481, 6970.477214208853, 6987.790366289019, 7071.045408869737, 7094.787156385317, 6713.530052896445, 6756.459483241427, 6799.762008329685, 6827.487938404739, 6832.077669514552, 6835.179778426422, 6921.688524016331, 6924.4114250287275, 6949.861002779815, 6961.2342642308295, 6322.2729386119345, 6448.29068802422, 6547.1161393213215, 6586.637734924625, 6591.209054913441, 5423.213634023124, 6666.433353165035, 6672.599644021374, 6688.163979502562, 6696.129503164558, 6426.4537514311805, 6522.706478208397, 6537.477001839937, 6542.182227221598, 6570.737170513126, 5470.781168753205, 5519.696416696959, 6156.907646385218, 6508.967455162694, 6649.4680425564875, 6284.654344010941, 6353.396427339396, 6381.87236255263, 6411.743530258924, 6438.195225596801], "value": 6137.803237108613, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.6990458673409, 807.2049031703773, 849.6516428264409, 1242.1677476091013, 1303.621949634606, 30606.23484412888, 31886.0, 39889.0, 39997.80001099998, 40004.19991600174, 40276.39585406221, 40710.0, 40745.59254407455, 40783.77648670544, 40785.796071019606, 7774.6890124395095, 7942.563159026251, 8201.26188643022, 8379.497230166178, 8406.495610263404, 8309.626066827008, 8406.873896891551, 8406.915930840705, 8418.957905210465, 8436.282915952152, 8407.201315874981, 8446.493210407396, 8452.366072544553, 8468.407211495198, 8468.78828029298, 8378.371622128365, 8387.664493420256, 8397.244248017647, 8404.327653787714, 8441.409101362908, 8310.085890552034, 8333.208345207195, 8348.874766878502, 8364.37267204956, 8364.58177091149, 38191.427128593095, 38346.80826595849, 38363.424548631796, 38432.61567384318, 38504.229915401535, 3590.120420496995, 3618.059304580802, 3636.381815091441, 3641.417373220272, 3641.9453708194396, 30755.0, 30799.230019249473, 31090.911818086257, 31095.22261943447, 31152.688473115206, 2634.802389820752, 2996.2659148508537, 3041.2548925513393, 3045.3909218156236, 3051.908442746721, 243559.78220108786, 244289.0, 289753.9999999979, 315024.0, 315964.84035159583, 49520.75239623779, 49539.50460495421, 49547.76130596764, 49730.0, 49805.50194498081, 50640.49359506358, 50714.0, 51097.744511277946, 51689.707757306365, 51873.2219016715, 14447.638809029859, 14464.493742719165, 14587.41650333996, 14653.780193297109, 14662.853371466363, 11606.767864642828, 11609.0, 11670.533178672928, 11723.824142637868, 11748.58879939198, 11424.200305978502, 14115.223662698445, 14126.011179217356, 14143.43426262938, 14144.64638384028, 6054.091886217015, 6085.330613632497, 6108.480779133734, 6117.388261173936, 6121.724522396503, 787.3032366355816, 851.6125163050797, 935.3125452792192, 966.1594412860856, 1033.8035773203217, 24190.63714044291, 24678.25965221046, 25028.4994300113, 25060.248192554252, 25329.0, 1048.528162326956, 1048.809601102754, 1070.1813112968584, 1080.3841810168128, 1147.1511081799408, 9919.0, 10321.535530901128, 10328.0, 12113.456174203517, 12922.612321630366, 337099.0, 339299.0, 339716.0, 339946.0, 340464.0, 55133.0, 55579.722101389234, 55678.44321556733, 55783.88432231333, 55828.16257756137, 1488.7990121333698, 1488.932998015092, 1508.5097343363232, 1522.7563589825797, 1530.4719871644265, 21507.139714411875, 22096.00567974413, 22881.771182287965, 23362.883185583963, 24208.75791242065, 37608.811955940044, 38103.42844857274, 38308.0, 38491.23017539689, 38491.807540962116, 270113.6494317516, 270197.0, 270820.0, 271210.0, 271306.0, 35880.0, 36400.81799590985, 36530.452043219906, 36661.08347291232, 36835.447468288534, 43474.913127172076, 45743.313850291604, 46131.76934115308, 46151.53848461607, 46408.30387544122, 29313.85343073271, 29946.850265748533, 30025.399492010474, 30686.079417617508, 31510.054698359087, 29849.70150298469, 29990.55014174833, 30315.0, 31343.68656313499, 31442.371152577278, 25415.87292063528, 34311.82844085764, 37629.81185094057, 37738.0, 37740.62259377371, 23932.162374316817, 28604.0, 28702.0, 29224.70775292305, 29881.402371952874, 20548.486287842923, 24550.386240344134, 24733.25800225996, 24826.50346993086, 24979.0], "value": 17448.527318200217, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.9167512335094, 436.5677978800988, 437.6302024789053, 446.6337603165405, 450.45719907511454, 14533.709325813477, 15125.470608528703, 19172.520686982814, 22510.09959601616, 22779.77220227797, 22431.214907478243, 23543.764562354372, 23548.646770298416, 23685.76314236857, 23732.64401033986, 5652.699879027826, 6116.938830611703, 6201.503879689625, 6334.3032266450655, 6389.424951754341, 6182.072689096649, 6297.023961285985, 6305.590136641126, 6308.211473565804, 6313.621182729028, 5955.659976505273, 6010.96994515029, 6166.475849552793, 6250.656213908233, 6280.591761535508, 5562.0, 6235.657038862861, 6236.282827474808, 6251.718672659718, 6254.9374506255035, 5522.3649280332875, 5549.694766787825, 5550.333959924795, 5558.861028474321, 5564.721763911788, 27755.86122069397, 27903.86048069767, 28104.0, 28260.85869570639, 28299.85850070757, 2094.7800480949586, 2153.6231159547015, 2185.4099393163915, 2185.4864106934856, 2189.861272138479, 18194.0, 18474.90762546192, 18548.629027419374, 18649.906750466296, 18738.625227495373, 1865.32848174657, 2122.1405330841035, 2147.0660262785736, 2199.692043113965, 2223.344113486525, 132883.6711632881, 153677.46322536742, 157777.0, 160297.19851400785, 185293.07353463146, 12153.939230303791, 12897.35513224335, 13159.53941612039, 13202.07585469027, 13387.933060334635, 16610.916945415196, 16631.002139871645, 16727.749083763752, 16783.580410489834, 16797.832021679627, 8842.646294148162, 9014.909850901407, 9019.909800901907, 9055.773605659911, 9095.36332456735, 7794.610269486503, 7888.881666775003, 8567.74296771098, 8597.398182127192, 8653.524056176844, 7858.035491127218, 8030.959845200737, 8118.878216826753, 8130.268275855083, 8229.300509456763, 2003.2387692676689, 2246.1913711064003, 2260.6043942310357, 2272.170657709924, 2438.378213555532, 924.2236521322121, 931.6971984105057, 949.4445749236636, 972.8394814855611, 994.8308787505997, 21051.842148681662, 21074.262400816206, 21157.78842211589, 21600.24399146054, 21830.454238643844, 930.2139691960283, 986.4180133721139, 987.7975015121883, 1036.103770238738, 1036.5646428500047, 8869.600867960957, 10753.032227099598, 11046.061084807721, 11199.328040317607, 11471.426428678533, 482520.76218856743, 531255.0, 535954.3202283963, 575360.0, 576759.0, 167417.3258267426, 168574.15712921356, 171286.0, 171767.0, 172975.13512432357, 1158.9130815188726, 1210.4371467267588, 1225.5771758743397, 1236.771197328497, 1238.2694210415898, 21275.255366062112, 21328.720276783442, 24039.27882163538, 24056.51886962216, 24284.0, 71362.6431867858, 71514.02763653632, 72067.0, 72205.0, 73177.0, 97086.0, 97392.0, 97778.0, 97968.0, 98352.0, 13702.314884255948, 16099.114548699465, 16545.91727041357, 16569.91715041417, 16582.66834663324, 18191.09044547741, 18279.0, 18440.81559184391, 18527.0, 18632.534186645444, 14746.926265369033, 14987.475438359605, 15200.239988000334, 15256.23718814077, 15313.770293445385, 12923.61229163089, 13015.479380824661, 13050.608481745563, 13357.065005449525, 13629.522966696119, 10755.677329680122, 15260.931734778458, 15417.229138542803, 15539.989900656421, 15654.45209417665, 16520.917395412947, 16850.66298674044, 16864.494065178063, 16978.0, 16984.57538561546, 14839.777403338743, 15051.548453546411, 15121.243937803285, 15168.848311517186, 15257.465988690343], "value": 11481.78607807138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.7285415771003, 1465.904716193448, 1488.0476495043174, 1497.7603583426653, 1508.0951429142513, 10312.0, 10562.155027597793, 11805.173637845344, 12095.0, 12323.753524929496, 11930.403479826018, 12205.57280495183, 12250.387480625977, 12258.264504129736, 12281.201721888072, 2443.2548072837735, 2506.5989441689335, 2510.899564017437, 2512.4095837478176, 2519.0301733832516, 2132.616129096762, 2240.843140980132, 2364.716234051916, 2392.449736560592, 2489.6887889013874, 2194.5501172259646, 2516.7986561075163, 2615.3592369869407, 2615.5292047431453, 2615.856127912964, 2559.6416501689687, 2560.4495033567873, 2566.4353842154696, 2570.3574106473384, 2576.5233431815172, 2529.519391315644, 2544.3384719972755, 2544.745525447459, 2547.9108231212003, 2551.7065537463245, 11891.167618266725, 11938.985186259179, 12026.218295810744, 12048.0, 12143.635690929286, 6493.70778314977, 6746.932530674729, 6749.392554670054, 6764.019217213526, 6777.390034896834, 3373.780704254216, 4232.238197124517, 4260.446142001551, 4280.871573852789, 4316.676249281285, 1221.908356873238, 1234.2224398628807, 1239.1202246405064, 1261.4323554400557, 1263.298869127634, 16889.408870689465, 16901.57746056346, 16905.40831070919, 16915.577110572212, 16974.830251697447, 5943.851403714942, 6009.489193418521, 6025.578209525379, 6155.876882462326, 6164.815055548341, 13960.232187229596, 13974.510892118727, 14153.363098660586, 14158.716825663429, 14169.0, 6686.1642294713165, 6719.160104986877, 6742.056112144303, 6743.325667433218, 6746.966265168741, 5975.462208401264, 6069.939300607026, 6070.7875224366935, 6119.632822030603, 6197.690115494297, 4158.625723684883, 4666.859994200179, 4705.1765940960195, 4760.547747963938, 4803.0, 1280.475005247844, 1326.383231797219, 1422.3101795629111, 1429.9356528956223, 1454.2147240490228, 804.6499772598938, 855.7860534866284, 857.5583574459158, 864.4856310495245, 870.1168314161132, 5662.57530685192, 7016.894746578805, 7288.132210291053, 7299.927000730031, 7320.194778574352, 800.3717082090648, 986.7286496213455, 992.9602815887428, 992.9950350248203, 995.4077323992213, 4644.117617652635, 4721.7403042832975, 4863.1732605457155, 4967.205247160509, 4978.800847966041, 14751.483698070731, 15070.171140587154, 15152.772708409382, 15598.610034748996, 15642.68714625701, 15199.848001520064, 15453.227338633249, 15457.613559661098, 15538.0, 15558.22208889551, 8492.830143397221, 8714.651413943498, 8732.170443807829, 8793.20861122502, 8799.12008799115, 6992.1958974718555, 7271.091113610799, 7311.670974806253, 7494.475386722877, 7826.295633393022, 13485.797713034117, 13782.379792909143, 13946.30268486592, 13982.510612128526, 14023.298835058411, 13906.404917707634, 14069.296535172996, 14209.076410033314, 14241.928790355982, 14295.28523573838, 8312.5428101455, 8834.734957951272, 9922.900770992486, 9926.0, 9931.801363972536, 10842.457877106272, 10940.890591093987, 11006.559737610727, 11043.834342484708, 11066.170037247077, 8735.344849136212, 9434.811303774024, 9437.528123593656, 9472.431654100776, 9481.09929556691, 7567.886481702669, 9200.447973121634, 9236.676716314896, 9272.860907086264, 9308.394954327947, 8296.377771667028, 8342.833143336979, 8429.0, 8430.15698430152, 8437.029741579798, 6062.393760623902, 6292.496600271876, 6351.2696039956, 6374.74501019954, 6512.902306465501, 6273.404026617464, 6286.591371560834, 6494.740210391531, 6536.967315163393, 6553.574017689026], "value": 5570.65279552395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.05230420837876, 403.8970062634026, 404.2945060868784, 412.8472465187881, 418.20958388645465, 6336.619802811834, 6407.231132264134, 6586.571872828269, 6611.008348747684, 6665.300143484937, 4930.901381972359, 5332.92000619991, 6228.688565571726, 6234.877722010036, 6326.255308518611, 3448.637893021234, 4527.3888025116685, 4668.976655116737, 4677.812887484497, 4687.086018226447, 4689.742064186468, 4690.97654511727, 4703.553162449562, 4704.317873908281, 4707.411573553306, 3831.7163750143595, 4597.379353787246, 4605.953940460586, 4616.376789133457, 4624.445066592014, 3900.7074469414624, 4049.666856463585, 4175.665946724254, 4194.475690538683, 4615.26155815068, 3821.159344944108, 3997.560268370477, 4004.2592120457807, 4015.156817068419, 4020.959790402117, 5186.377634683862, 6128.804883047763, 6314.368563143695, 6316.74733010679, 6336.429721325057, 4949.950500494985, 5293.655912365684, 5514.283143191399, 5550.611457197998, 5584.078627026536, 6865.0, 6868.862622747567, 6871.793846184622, 6878.312168783132, 6900.930990690129, 4016.297147999118, 4149.232392007489, 4564.406627138484, 4584.885377865547, 4588.24293991491, 6345.96827015862, 6822.3859852613505, 7013.473989450812, 7178.856422871513, 7250.918359697161, 4595.1728688835665, 4643.4660014098135, 4653.230882984725, 4657.67396282257, 4658.231391820379, 4723.834665786681, 4726.95273047272, 4741.193997020515, 4752.572268495852, 4761.52384761528, 5050.797968081309, 5061.392632884077, 5535.474129957647, 5535.474129957647, 5546.168074788814, 6860.725570977205, 6876.17485901685, 6876.553024053421, 6905.75829845953, 6907.20567134786, 1410.8448070712213, 1431.5347512058622, 1530.7703844423204, 1557.0035177486418, 1635.378556148656, 1036.8859425463193, 1066.6027503969824, 1069.1660504806264, 1071.4321409652741, 1076.1283360478037, 5710.229119068873, 6496.967515162489, 6498.285188629247, 6499.155109835691, 6523.249826269945, 5817.185594016841, 6508.511861610398, 6533.24867640218, 7131.322524360177, 7155.248698886646, 6448.677566121769, 6458.192725909262, 6487.734891696074, 6490.740370385226, 6555.968079630992, 3269.8692052318115, 3396.4735466002808, 3700.796456194881, 3735.869244576481, 3757.0, 6235.96882015587, 7355.448341374418, 7391.445641576904, 7406.185319614838, 7432.219616940251, 7364.3003914628025, 7365.263473652699, 7388.261173882676, 7390.22402647725, 7397.556146631219, 6492.532837521711, 7092.716291348288, 7293.52592081513, 7371.299726525972, 7409.629518524052, 7108.609026503592, 7136.85726285461, 7148.106486689164, 7154.964225178841, 7160.890062040392, 6438.197304754676, 6601.207855057424, 7039.225685174729, 7048.894266585903, 7054.224035356004, 7390.224026477143, 7396.564988460074, 7418.814529636802, 7420.332170104501, 7450.701971921062, 4644.0, 4663.580277775017, 4686.625069994324, 4688.953110468851, 4697.271922851964, 6004.3280740072, 7033.613151276729, 7161.4986950912935, 7203.747868824566, 7211.783646490614, 5548.805791797267, 6796.89296318143, 6906.37842594169, 6936.127495292778, 6963.09479767627, 6213.937860621517, 6326.493880489457, 6335.366463353812, 6895.793126206222, 6918.757843475454, 5819.679917604572, 6154.569180157346, 6712.731490740316, 6780.288069752605, 6801.965990170018, 6560.803175904731, 6619.702113404812, 6939.583624982517, 6947.861042779015, 6985.545939514119, 6733.0, 6746.156730408699, 6751.797446076625, 6762.797116086525, 6855.177378714387], "value": 4969.338249631703, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.6577559883456, 745.9142198647155, 818.7134502923981, 847.0724556610511, 862.1077185113409, 14336.64158396038, 14943.252837358115, 16762.74855877163, 17166.570835729097, 17274.740878886827, 16162.030278183318, 17716.911415442904, 17968.55078623035, 18027.999999999967, 18193.54516137094, 976.72651657536, 998.5456617239139, 1004.1615251265202, 1024.0834453164414, 1024.5799222318851, 982.641335912394, 1036.7097212780434, 1037.9065884070433, 1038.70396936873, 1038.8805287391972, 954.3319676226669, 1063.5958335832372, 1071.3464786480279, 1072.5656109275717, 1072.908802751767, 1060.464465444953, 1062.3785085724821, 1065.0148612533376, 1067.3809190669392, 1070.8661417322835, 774.6939958716275, 775.1202385292734, 775.9495632783908, 778.9454738168331, 794.241499368102, 17256.137193140294, 17513.824861751473, 17599.912000440043, 17651.558711032198, 17809.287628494843, 17731.468055958212, 17824.0, 17892.55268618282, 17977.73033404487, 18293.90853045726, 14645.707085858223, 14932.402703891832, 15015.474458394014, 15025.0, 15068.54794356171, 3593.2274560969518, 3604.3692353838237, 3631.400818864884, 3645.6354364563595, 3663.7984910829896, 149886.25056874755, 151589.24205378903, 153171.46828531683, 153887.46112538956, 155886.0, 13608.795868061788, 13698.041137120306, 13787.034907556375, 13800.723985520233, 13949.58151255464, 26188.214353569423, 26429.33926651849, 26500.734992650214, 26682.332941676224, 26931.057412990453, 7674.194209608022, 7838.176991415819, 7906.604669766489, 8094.676212951416, 8144.837103257902, 3934.075492259374, 3982.880513584597, 4012.959870401317, 4019.0756126091164, 4020.1758639478835, 4311.374850646639, 4416.028473735773, 4476.843310484118, 4522.570355816192, 4526.298423744325, 870.8026463612533, 947.1286416496839, 957.0238356875948, 969.4813274897896, 981.7103954333488, 637.5855693799068, 643.7489379142138, 648.4164252173082, 654.9214094308618, 671.2549070531705, 11379.829302560469, 11747.706307342385, 12378.75242495145, 12957.611271661865, 13073.15024523422, 643.7264162730914, 649.5940037476577, 657.3525077798365, 661.147120214927, 670.5574320948248, 5295.735213239323, 5739.368669446358, 5897.823065308047, 6514.73941042353, 6714.697838597275, 137570.0, 138534.92197617044, 140483.0, 140706.29646851702, 141254.0, 119129.0, 121861.78138218682, 123247.0, 124703.0, 125904.74095259114, 106987.4650626742, 109359.0, 111984.0, 114118.0, 114565.0, 12580.685482862635, 12712.491500340253, 13212.801807973081, 13244.602661920158, 13279.80080298777, 37890.24219515539, 44312.4490642826, 45841.77079114583, 46228.768856155504, 46356.841078973295, 60138.699306503186, 62101.75796484135, 62316.75366492735, 62447.375526244155, 62788.744225116156, 16220.594485137966, 17228.224729886933, 17608.207630656394, 17787.46637600874, 17796.199171037075, 15999.600009999844, 18456.815431846047, 18647.0, 18922.810771892655, 19134.330298439487, 17376.478705638852, 17754.467365979042, 18021.819781802013, 18106.637867242844, 18180.909095454437, 17057.658846823244, 17456.73814892752, 17804.0, 17958.371456998942, 17988.730169047212, 17446.214920328362, 17491.47525574235, 17505.124743762506, 17762.733558996366, 17860.821391785918, 11822.0, 11826.349550774446, 11944.761104777683, 12357.691057723629, 12442.129050966345, 11956.175298804781, 12937.353132343533, 13040.934795325962, 13664.93167534156, 13676.0], "value": 8907.97589523565, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 116.0, 114.0, 115.0, 116.0, 116.0, 116.0, 115.0, 113.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 108.0, 109.0, 142.0, 152.0, 148.0, 110.0, 112.0, 134.0, 109.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 243.0, 236.0, 257.0, 220.0, 224.0, 276.0, 278.0, 191.0, 196.0], "value": 235.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 62.0, 61.0, 84.0, 63.0, 64.0, 90.0, 50.0, 80.0, 82.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 67.0, 93.0, 72.0, 71.0, 70.0, 86.0, 68.0, 67.0, 88.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 90.0, 89.0, 90.0, 133.0, 97.0, 90.0, 90.0, 90.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 97.0, 97.0, 98.0, 128.0, 103.0, 152.0, 140.0, 95.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 93.0, 92.0, 95.0, 92.0, 96.0, 92.0, 92.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 47.0, 73.0, 82.0, 78.0, 77.0, 79.0, 50.0, 50.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 66.0, 60.0, 51.0, 69.0, 86.0, 87.0, 84.0, 82.0, 62.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 81.0, 85.0, 85.0, 87.0, 86.0, 89.0, 113.0, 91.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 134.0, 135.0, 156.0, 155.0, 150.0, 128.0, 145.0, 138.0, 152.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 185.0, 196.0, 231.0, 202.0, 177.0, 225.0, 232.0, 223.0, 178.0], "value": 202.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 64.0, 70.0, 69.0, 67.0, 67.0, 70.0, 65.0, 68.0], "value": 67.6, "unit": "score"}]}]} 10:17:07 INFO - Return code: 0 10:17:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:17:07 INFO - # TBPL SUCCESS # 10:17:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:17:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:17:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:17:07 INFO - 2017-06-07 10:17:07.838 screenresolution[1667:11393] starting screenresolution argv=screenresolution get 10:17:07 INFO - 2017-06-07 10:17:07.841 screenresolution[1667:11393] Display 0: 1600x1200x32@60 10:17:07 INFO - 2017-06-07 10:17:07.847 screenresolution[1668:11396] starting screenresolution argv=screenresolution list 10:17:07 INFO - Available Modes on Display 0 10:17:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:17:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:17:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:17:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:17:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:17:08 INFO - Intel Iris: 10:17:08 INFO - Chipset Model: Intel Iris 10:17:08 INFO - Type: GPU 10:17:08 INFO - Bus: Built-In 10:17:08 INFO - VRAM (Dynamic, Max): 1536 MB 10:17:08 INFO - Vendor: Intel (0x8086) 10:17:08 INFO - Device ID: 0x0a2e 10:17:08 INFO - Revision ID: 0x0009 10:17:08 INFO - Displays: 10:17:08 INFO - Display: 10:17:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:17:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:17:08 INFO - Display Serial Number: 1600x1200 60 10:17:08 INFO - Main Display: Yes 10:17:08 INFO - Mirror: Off 10:17:08 INFO - Online: Yes 10:17:08 INFO - Rotation: Supported 10:17:08 INFO - Return code: 0 10:17:08 INFO - Running post-action listener: _resource_record_post_action 10:17:08 INFO - [mozharness: 2017-06-07 17:17:08.083116Z] Finished run-tests step (success) 10:17:08 INFO - Running post-run listener: _resource_record_post_run 10:17:08 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 40266752; Write bytes: 573267968; Read time: 264; Write time: 1765 10:17:08 INFO - TinderboxPrint: CPU usage
53.1% 10:17:08 INFO - TinderboxPrint: I/O read bytes / time
40,266,752 / 264 10:17:08 INFO - TinderboxPrint: I/O write bytes / time
573,267,968 / 1,765 10:17:08 INFO - TinderboxPrint: CPU idle
2,356.8 (46.9%) 10:17:08 INFO - TinderboxPrint: CPU system
1,015.3 (20.2%) 10:17:08 INFO - TinderboxPrint: CPU user
1,657.6 (33.0%) 10:17:08 INFO - TinderboxPrint: Swap in / out
287,666,176 / 0 10:17:08 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230670848; Write bytes: 233953280; Read time: 16811; Write time: 499 10:17:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:17:08 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 39304192; Write bytes: 320800768; Read time: 252; Write time: 1215 10:17:08 INFO - Running post-run listener: _upload_blobber_files 10:17:08 INFO - Blob upload gear active. 10:17:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:17:08 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 10:17:08 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'] 10:17:08 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 10:17:08 INFO - (blobuploader) - INFO - Open directory for files ... 10:17:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:17:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:17:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:17:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:09 INFO - (blobuploader) - INFO - Done attempting. 10:17:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:17:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:17:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:17:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:10 INFO - (blobuploader) - INFO - Done attempting. 10:17:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:17:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:17:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:17:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:11 INFO - (blobuploader) - INFO - Done attempting. 10:17:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:17:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:17:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:13 INFO - (blobuploader) - INFO - Done attempting. 10:17:13 INFO - (blobuploader) - INFO - Iteration through files over. 10:17:13 INFO - Return code: 0 10:17:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:17:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:17:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b58ac62408b6754dc85877c124cbef5da5da234eb8693dc5169e347233f0d91cece78951217c7b97241d948be903c683dedaabe57f01f39818bf4bb6121f6d4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdabe4a4e0a22687753785aeffccb57928c8f6506e8b7eff33860929311d6b1bec5b2cf103ee04a1cba124737b1be24181e5b4b4097448317c2df0bbb9c17b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/171501e34d7de4ab3531e8edd7b62d8e4146f58afa9b5c0ef3316743eb04a1673f370e9af83dc76e107dc83e98fb3fd4d95d4446d321f77a9cd08d537d7cc2f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ebc18f83191f23e76150975a31f1d08934fcec64cfa15f76e78b06de5b2fbba64c3840a46d0ecf29279eea424b02229aa8fdcb1c674808fdc0af4b22172c19e"} 10:17:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:17:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:17:13 INFO - Contents: 10:17:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b58ac62408b6754dc85877c124cbef5da5da234eb8693dc5169e347233f0d91cece78951217c7b97241d948be903c683dedaabe57f01f39818bf4bb6121f6d4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdabe4a4e0a22687753785aeffccb57928c8f6506e8b7eff33860929311d6b1bec5b2cf103ee04a1cba124737b1be24181e5b4b4097448317c2df0bbb9c17b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/171501e34d7de4ab3531e8edd7b62d8e4146f58afa9b5c0ef3316743eb04a1673f370e9af83dc76e107dc83e98fb3fd4d95d4446d321f77a9cd08d537d7cc2f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ebc18f83191f23e76150975a31f1d08934fcec64cfa15f76e78b06de5b2fbba64c3840a46d0ecf29279eea424b02229aa8fdcb1c674808fdc0af4b22172c19e"} 10:17:13 INFO - Running post-run listener: copy_logs_to_upload_dir 10:17:13 INFO - Copying logs to upload dir... 10:17:13 INFO - mkdir: /builds/slave/test/build/upload/logs 10:17:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.483370 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-07 10:17:13.797493) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 10:17:13.801040) ========= 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.CSaXgmznA6/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.qOqhLjOYs4/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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/2b58ac62408b6754dc85877c124cbef5da5da234eb8693dc5169e347233f0d91cece78951217c7b97241d948be903c683dedaabe57f01f39818bf4bb6121f6d4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdabe4a4e0a22687753785aeffccb57928c8f6506e8b7eff33860929311d6b1bec5b2cf103ee04a1cba124737b1be24181e5b4b4097448317c2df0bbb9c17b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/171501e34d7de4ab3531e8edd7b62d8e4146f58afa9b5c0ef3316743eb04a1673f370e9af83dc76e107dc83e98fb3fd4d95d4446d321f77a9cd08d537d7cc2f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ebc18f83191f23e76150975a31f1d08934fcec64cfa15f76e78b06de5b2fbba64c3840a46d0ecf29279eea424b02229aa8fdcb1c674808fdc0af4b22172c19e"} build_url:https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/TiHy-PZ7RzSK79SesJHHmw/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/2b58ac62408b6754dc85877c124cbef5da5da234eb8693dc5169e347233f0d91cece78951217c7b97241d948be903c683dedaabe57f01f39818bf4bb6121f6d4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0bdabe4a4e0a22687753785aeffccb57928c8f6506e8b7eff33860929311d6b1bec5b2cf103ee04a1cba124737b1be24181e5b4b4097448317c2df0bbb9c17b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/171501e34d7de4ab3531e8edd7b62d8e4146f58afa9b5c0ef3316743eb04a1673f370e9af83dc76e107dc83e98fb3fd4d95d4446d321f77a9cd08d537d7cc2f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ebc18f83191f23e76150975a31f1d08934fcec64cfa15f76e78b06de5b2fbba64c3840a46d0ecf29279eea424b02229aa8fdcb1c674808fdc0af4b22172c19e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-07 10:17:13.834793) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 10:17:13.835179) ========= 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.CSaXgmznA6/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.qOqhLjOYs4/Listeners TMPDIR=/var/folders/pm/th83h8l52qd0r5qqq2qsg_g400000w/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.004478 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-07 10:17:13.883680) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 10:17:13.884033) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-07 10:17:13.884368) ========= ========= Total master_lag: 0.30 =========