builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0277 starttime: 1497410161.12 results: success (0) revision: b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.121976) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.122415) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.122691) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.151768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.152075) ========= 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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-13 20:16:01-- 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% 16.8M=0.001s 2017-06-13 20:16:01 (16.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.482941 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.660539) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.660849) ========= 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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.025866 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:01.708617) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 20:16:01.708973) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c --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 b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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-13 20:16:01,810 truncating revision to first 12 chars 2017-06-13 20:16:01,811 Setting DEBUG logging. 2017-06-13 20:16:01,811 attempt 1/10 2017-06-13 20:16:01,811 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b266a8d8fd59?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 20:16:02,997 unpacking tar archive at: mozilla-central-b266a8d8fd59/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458484 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 20:16:03.192845) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:03.193150) ========= script_repo_revision: b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:03.193515) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:03.193817) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 20:16:03.228431) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-13 20:16:03.228696) ========= /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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:16:03 INFO - MultiFileLogger online at 20170613 20:16:03 in /builds/slave/test 20:16:03 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 20:16:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:16:03 INFO - {'append_to_log': False, 20:16:03 INFO - 'base_work_dir': '/builds/slave/test', 20:16:03 INFO - 'blob_upload_branch': 'Firefox', 20:16:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:16:03 INFO - 'branch': 'Firefox', 20:16:03 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:03 INFO - 'config_files': ('talos/mac_config.py',), 20:16:03 INFO - 'default_actions': ('clobber', 20:16:03 INFO - 'read-buildbot-config', 20:16:03 INFO - 'download-and-extract', 20:16:03 INFO - 'populate-webroot', 20:16:03 INFO - 'create-virtualenv', 20:16:03 INFO - 'install', 20:16:03 INFO - 'setup-mitmproxy', 20:16:03 INFO - 'run-tests'), 20:16:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:03 INFO - 'download_minidump_stackwalk': True, 20:16:03 INFO - 'download_symbols': 'ondemand', 20:16:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:16:03 INFO - 'tooltool.py': '/tools/tooltool.py', 20:16:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:16:03 INFO - '/tools/misc-python/virtualenv.py')}, 20:16:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:03 INFO - 'gecko_profile': False, 20:16:03 INFO - 'gecko_profile_interval': 0, 20:16:03 INFO - 'installer_path': 'installer.exe', 20:16:03 INFO - 'log_level': 'info', 20:16:03 INFO - 'log_name': 'talos', 20:16:03 INFO - 'log_to_console': True, 20:16:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:16:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:16:03 INFO - 'opt_config_files': (), 20:16:03 INFO - 'pip_index': False, 20:16:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:16:03 INFO - 'cmd': ('bash', 20:16:03 INFO - '-c', 20:16:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:16:03 INFO - 'enabled': True, 20:16:03 INFO - 'halt_on_failure': False, 20:16:03 INFO - 'name': 'check_screen_resolution'},), 20:16:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:16:03 INFO - 'cmd': ('bash', 20:16:03 INFO - '-c', 20:16:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:16:03 INFO - 'enabled': True, 20:16:03 INFO - 'halt_on_failure': False, 20:16:03 INFO - 'name': 'check_screen_resolution'},), 20:16:03 INFO - 'run_cmd_checks_enabled': True, 20:16:03 INFO - 'suite': 'dromaeojs-e10s', 20:16:03 INFO - 'system_bits': '32', 20:16:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:03 INFO - 'title': 't-yosemite-r7-0277', 20:16:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:16:03 INFO - 'use_talos_json': True, 20:16:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:16:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:03 INFO - 'work_dir': 'build'} 20:16:03 INFO - [mozharness: 2017-06-14 03:16:03.441882Z] Running clobber step. 20:16:03 INFO - Running pre-action listener: _resource_record_pre_action 20:16:03 INFO - Running main action method: clobber 20:16:03 INFO - rmtree: /builds/slave/test/build 20:16:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:16:05 INFO - Running post-action listener: _resource_record_post_action 20:16:05 INFO - [mozharness: 2017-06-14 03:16:05.893558Z] Finished clobber step (success) 20:16:05 INFO - [mozharness: 2017-06-14 03:16:05.893689Z] Running read-buildbot-config step. 20:16:05 INFO - Running pre-action listener: _resource_record_pre_action 20:16:05 INFO - Running main action method: read_buildbot_config 20:16:05 INFO - Using buildbot properties: 20:16:05 INFO - { 20:16:05 INFO - "project": "", 20:16:05 INFO - "product": "firefox", 20:16:05 INFO - "who": "kwierso@gmail.com", 20:16:05 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 20:16:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 20:16:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:16:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 20:16:05 INFO - "stage_platform": "macosx64", 20:16:05 INFO - "basedir": "/builds/slave/test", 20:16:05 INFO - "buildnumber": 94, 20:16:05 INFO - "platform": "yosemite_r7", 20:16:05 INFO - "parent_task_id": "JydRJCnVQrij1JAs5zBJTQ", 20:16:05 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:16:05 INFO - "slavebuilddir": "test", 20:16:05 INFO - "taskId": "AS_yXimGRdWeW1zl4_T2Vw", 20:16:05 INFO - "branch": "mozilla-central", 20:16:05 INFO - "script_repo_revision": "production", 20:16:05 INFO - "revision": "b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c", 20:16:05 INFO - "slavename": "t-yosemite-r7-0277", 20:16:05 INFO - "repo_path": "mozilla-central" 20:16:05 INFO - } 20:16:05 INFO - Finding installer, test and symbols from parent task. 20:16:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AS_yXimGRdWeW1zl4_T2Vw'}, attempt #1 20:16:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AS_yXimGRdWeW1zl4_T2Vw'}, attempt #1 20:16:06 INFO - Task dependencies: OKAqGZXyRfyeJz-7Z2Xodg JydRJCnVQrij1JAs5zBJTQ 20:16:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ'}, attempt #1 20:16:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 20:16:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/test_packages.json 20:16:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/target.crashreporter-symbols.zip 20:16:07 INFO - Running post-action listener: _resource_record_post_action 20:16:07 INFO - [mozharness: 2017-06-14 03:16:07.408439Z] Finished read-buildbot-config step (success) 20:16:07 INFO - [mozharness: 2017-06-14 03:16:07.408552Z] Running download-and-extract step. 20:16:07 INFO - Running pre-action listener: _resource_record_pre_action 20:16:07 INFO - Running main action method: download_and_extract 20:16:07 INFO - mkdir: /builds/slave/test/build/tests 20:16: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')]} 20:16:07 INFO - trying https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 20:16:07 INFO - Downloading https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 20:16:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 20:16:08 INFO - Downloaded 1328 bytes. 20:16:08 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 20:16:08 INFO - Using the following test package requirements: 20:16:08 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 20:16:08 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 20:16:08 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 20:16:08 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 20:16:08 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'jsshell-mac.zip'], 20:16:08 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 20:16:08 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 20:16:08 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 20:16:08 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 20:16:08 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 20:16:08 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 20:16:08 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 20:16:08 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:16:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 20:16:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:16:08 INFO - Fetch https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 20:16:10 INFO - Content-Length response header: 59542632 20:16:10 INFO - Bytes received: 59542632 20:16:28 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:16:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 20:16:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:16:28 INFO - Fetch https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 20:16:31 INFO - Content-Length response header: 59542632 20:16:31 INFO - Bytes received: 59542632 20:16:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 20:16:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:16:49 INFO - Fetch https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 20:16:49 INFO - Content-Length response header: 14450022 20:16:49 INFO - Bytes received: 14450022 20:16: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')]} 20:16:50 INFO - trying https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 20:16:50 INFO - Downloading https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 20:16:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 20:16:52 INFO - Downloaded 69607793 bytes. 20:16:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 20:16:52 INFO - mkdir: /builds/slave/test/properties 20:16:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:16:52 INFO - Writing to file /builds/slave/test/properties/build_url 20:16:52 INFO - Contents: 20:16:52 INFO - build_url:https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 20:16:52 INFO - Running post-action listener: _resource_record_post_action 20:16:52 INFO - Running post-action listener: set_extra_try_arguments 20:16:52 INFO - [mozharness: 2017-06-14 03:16:52.446417Z] Finished download-and-extract step (success) 20:16:52 INFO - [mozharness: 2017-06-14 03:16:52.446565Z] Running populate-webroot step. 20:16:52 INFO - Running pre-action listener: _resource_record_pre_action 20:16:52 INFO - Running main action method: populate_webroot 20:16:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:16:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:16:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:16:52 INFO - '--tppagecycles', 20:16:52 INFO - '7'], 20:16:52 INFO - 'tests': ['tsvgm']}}, 20:16:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:16:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:16:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:16:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['damp', 'tps']}, 20:16:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'tests': ['damp', 'tps']}, 20:16:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['dromaeo_dom']}, 20:16:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:16:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:16:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:16:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:16:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['a11yr', 20:16:52 INFO - 'ts_paint', 20:16:52 INFO - 'tpaint', 20:16:52 INFO - 'sessionrestore', 20:16:52 INFO - 'sessionrestore_no_auto_restore', 20:16:52 INFO - 'tabpaint']}, 20:16:52 INFO - 'other-e10s': {'tests': ['a11yr', 20:16:52 INFO - 'ts_paint', 20:16:52 INFO - 'tpaint', 20:16:52 INFO - 'sessionrestore', 20:16:52 INFO - 'sessionrestore_no_auto_restore', 20:16:52 INFO - 'tabpaint']}, 20:16:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:16:52 INFO - 'ts_paint', 20:16:52 INFO - 'tpaint', 20:16:52 INFO - 'sessionrestore', 20:16:52 INFO - 'sessionrestore_no_auto_restore', 20:16:52 INFO - 'tabpaint']}, 20:16:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:16:52 INFO - 'ts_paint', 20:16:52 INFO - 'tpaint', 20:16:52 INFO - 'sessionrestore', 20:16:52 INFO - 'sessionrestore_no_auto_restore', 20:16:52 INFO - 'tabpaint']}, 20:16:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['a11yr', 20:16:52 INFO - 'ts_paint', 20:16:52 INFO - 'tpaint', 20:16:52 INFO - 'sessionrestore', 20:16:52 INFO - 'sessionrestore_no_auto_restore', 20:16:52 INFO - 'tabpaint']}, 20:16:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['a11yr', 20:16:52 INFO - 'ts_paint', 20:16:52 INFO - 'tpaint', 20:16:52 INFO - 'sessionrestore', 20:16:52 INFO - 'sessionrestore_no_auto_restore', 20:16:52 INFO - 'tabpaint']}, 20:16:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 20:16:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 20:16:52 INFO - 'bloom_basic_ref']}, 20:16:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:16:52 INFO - 'talos_options': ['--mitmproxy', 20:16:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:16:52 INFO - '--firstNonBlankPaint'], 20:16:52 INFO - 'tests': ['quantum_pageload_google', 20:16:52 INFO - 'quantum_pageload_youtube', 20:16:52 INFO - 'quantum_pageload_amazon', 20:16:52 INFO - 'quantum_pageload_facebook']}, 20:16:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['tsvgx', 20:16:52 INFO - 'tsvgr_opacity', 20:16:52 INFO - 'tart', 20:16:52 INFO - 'tscrollx', 20:16:52 INFO - 'cart', 20:16:52 INFO - 'tsvg_static']}, 20:16:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:16:52 INFO - 'tsvgr_opacity', 20:16:52 INFO - 'tart', 20:16:52 INFO - 'tscrollx', 20:16:52 INFO - 'cart', 20:16:52 INFO - 'tsvg_static']}, 20:16:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'talos_options': ['--disable-e10s'], 20:16:52 INFO - 'tests': ['tp5o']}, 20:16:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:16:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'talos_options': ['--disable-e10s', 20:16:52 INFO - '--xperf_path', 20:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:52 INFO - 'tests': ['tp5n']}, 20:16:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:16:52 INFO - 'talos_options': ['--xperf_path', 20:16:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:52 INFO - 'tests': ['tp5n']}}, 20:16:52 INFO - 'talos.zip': {'path': '', 20:16:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:16:52 INFO - Running post-action listener: _resource_record_post_action 20:16:52 INFO - [mozharness: 2017-06-14 03:16:52.469803Z] Finished populate-webroot step (success) 20:16:52 INFO - [mozharness: 2017-06-14 03:16:52.469943Z] Running create-virtualenv step. 20:16:52 INFO - Running pre-action listener: _resource_record_pre_action 20:16:52 INFO - Running main action method: create_virtualenv 20:16:52 INFO - Creating virtualenv /builds/slave/test/build/venv 20:16:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:16:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:16:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:16:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:16:52 INFO - Using real prefix '/tools/python27' 20:16:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:16:53 INFO - Installing distribute.............................................................................................................................................................................................done. 20:16:56 INFO - Installing pip.................done. 20:16:56 INFO - Return code: 0 20:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:16:56 INFO - Reading from file tmpfile_stdout 20:16:56 INFO - Output received: 20:16:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:16:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:56 INFO - 'HOME': '/Users/cltbld', 20:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:56 INFO - 'LOGNAME': 'cltbld', 20:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:56 INFO - 'MOZ_NO_REMOTE': '1', 20:16:56 INFO - 'NO_EM_RESTART': '1', 20:16:56 INFO - 'PAGER': '/bin/cat', 20:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:56 INFO - 'PWD': '/builds/slave/test', 20:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:56 INFO - 'SHELL': '/bin/bash', 20:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:16:56 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:56 INFO - 'USER': 'cltbld', 20:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:56 INFO - 'XPC_FLAGS': '0x0', 20:16:56 INFO - 'XPC_SERVICE_NAME': '0', 20:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:56 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 20:16:56 INFO - Cleaning up... 20:16:56 INFO - Return code: 0 20:16:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:56 INFO - 'HOME': '/Users/cltbld', 20:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:56 INFO - 'LOGNAME': 'cltbld', 20:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:56 INFO - 'MOZ_NO_REMOTE': '1', 20:16:56 INFO - 'NO_EM_RESTART': '1', 20:16:56 INFO - 'PAGER': '/bin/cat', 20:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:56 INFO - 'PWD': '/builds/slave/test', 20:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:56 INFO - 'SHELL': '/bin/bash', 20:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:16:56 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:56 INFO - 'USER': 'cltbld', 20:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:56 INFO - 'XPC_FLAGS': '0x0', 20:16:56 INFO - 'XPC_SERVICE_NAME': '0', 20:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:56 INFO - Downloading/unpacking psutil>=3.1.1 20:16: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 20:16: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 20:16: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 20:16: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 20:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:16:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:16:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:16:59 INFO - Installing collected packages: psutil 20:16:59 INFO - Running setup.py install for psutil 20:16:59 INFO - building 'psutil._psutil_osx' extension 20:16:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:17:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:17:00 INFO - building 'psutil._psutil_posix' extension 20:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:17:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:17:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:17:00 INFO - ^ 20:17:00 INFO - 1 warning generated. 20:17:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:17:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:00 INFO - Successfully installed psutil 20:17:00 INFO - Cleaning up... 20:17:00 INFO - Return code: 0 20:17:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:00 INFO - 'HOME': '/Users/cltbld', 20:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:00 INFO - 'LOGNAME': 'cltbld', 20:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:00 INFO - 'MOZ_NO_REMOTE': '1', 20:17:00 INFO - 'NO_EM_RESTART': '1', 20:17:00 INFO - 'PAGER': '/bin/cat', 20:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:00 INFO - 'PWD': '/builds/slave/test', 20:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:00 INFO - 'SHELL': '/bin/bash', 20:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:00 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:00 INFO - 'USER': 'cltbld', 20:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:00 INFO - 'XPC_FLAGS': '0x0', 20:17:00 INFO - 'XPC_SERVICE_NAME': '0', 20:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:17:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:17:02 INFO - Installing collected packages: mozsystemmonitor 20:17:02 INFO - Running setup.py install for mozsystemmonitor 20:17:02 INFO - Successfully installed mozsystemmonitor 20:17:02 INFO - Cleaning up... 20:17:02 INFO - Return code: 0 20:17:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:02 INFO - 'HOME': '/Users/cltbld', 20:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:02 INFO - 'LOGNAME': 'cltbld', 20:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:02 INFO - 'MOZ_NO_REMOTE': '1', 20:17:02 INFO - 'NO_EM_RESTART': '1', 20:17:02 INFO - 'PAGER': '/bin/cat', 20:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:02 INFO - 'PWD': '/builds/slave/test', 20:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:02 INFO - 'SHELL': '/bin/bash', 20:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:02 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:02 INFO - 'USER': 'cltbld', 20:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:02 INFO - 'XPC_FLAGS': '0x0', 20:17:02 INFO - 'XPC_SERVICE_NAME': '0', 20:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:02 INFO - Downloading/unpacking jsonschema==2.5.1 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:17:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:04 INFO - Downloading functools32-3.2.3-2.tar.gz 20:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:17:04 INFO - warning: no files found matching '*.txt' 20:17:04 INFO - no previously-included directories found matching 'build' 20:17:04 INFO - no previously-included directories found matching 'dist' 20:17:04 INFO - no previously-included directories found matching '.git*' 20:17:04 INFO - Installing collected packages: jsonschema, functools32 20:17:04 INFO - Running setup.py install for functools32 20:17:04 INFO - warning: no files found matching '*.txt' 20:17:04 INFO - no previously-included directories found matching 'build' 20:17:04 INFO - no previously-included directories found matching 'dist' 20:17:04 INFO - no previously-included directories found matching '.git*' 20:17:04 INFO - Successfully installed jsonschema functools32 20:17:04 INFO - Cleaning up... 20:17:04 INFO - Return code: 0 20:17:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:04 INFO - 'HOME': '/Users/cltbld', 20:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:04 INFO - 'LOGNAME': 'cltbld', 20:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:04 INFO - 'MOZ_NO_REMOTE': '1', 20:17:04 INFO - 'NO_EM_RESTART': '1', 20:17:04 INFO - 'PAGER': '/bin/cat', 20:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:04 INFO - 'PWD': '/builds/slave/test', 20:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:04 INFO - 'SHELL': '/bin/bash', 20:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:04 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:04 INFO - 'USER': 'cltbld', 20:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:04 INFO - 'XPC_FLAGS': '0x0', 20:17:04 INFO - 'XPC_SERVICE_NAME': '0', 20:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:17:05 INFO - Cleaning up... 20:17:05 INFO - Return code: 0 20:17:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:05 INFO - 'HOME': '/Users/cltbld', 20:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:05 INFO - 'LOGNAME': 'cltbld', 20:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:05 INFO - 'MOZ_NO_REMOTE': '1', 20:17:05 INFO - 'NO_EM_RESTART': '1', 20:17:05 INFO - 'PAGER': '/bin/cat', 20:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:05 INFO - 'PWD': '/builds/slave/test', 20:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:05 INFO - 'SHELL': '/bin/bash', 20:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:05 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:05 INFO - 'USER': 'cltbld', 20:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:05 INFO - 'XPC_FLAGS': '0x0', 20:17:05 INFO - 'XPC_SERVICE_NAME': '0', 20:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:05 INFO - Downloading/unpacking blobuploader==1.2.4 20:17: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 20:17: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 20:17: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 20:17: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 20:17:06 INFO - Downloading blobuploader-1.2.4.tar.gz 20:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:17:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:17:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:07 INFO - Downloading docopt-0.6.1.tar.gz 20:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:17:07 INFO - Installing collected packages: blobuploader, requests, docopt 20:17:07 INFO - Running setup.py install for blobuploader 20:17:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:17:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:17:07 INFO - Running setup.py install for requests 20:17:08 INFO - Running setup.py install for docopt 20:17:08 INFO - Successfully installed blobuploader requests docopt 20:17:08 INFO - Cleaning up... 20:17:08 INFO - Return code: 0 20:17:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:08 INFO - 'HOME': '/Users/cltbld', 20:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:08 INFO - 'LOGNAME': 'cltbld', 20:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:08 INFO - 'MOZ_NO_REMOTE': '1', 20:17:08 INFO - 'NO_EM_RESTART': '1', 20:17:08 INFO - 'PAGER': '/bin/cat', 20:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:08 INFO - 'PWD': '/builds/slave/test', 20:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:08 INFO - 'SHELL': '/bin/bash', 20:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:08 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:08 INFO - 'USER': 'cltbld', 20:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:08 INFO - 'XPC_FLAGS': '0x0', 20:17:08 INFO - 'XPC_SERVICE_NAME': '0', 20:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:08 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-c5gEnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:08 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-7VUecK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-4ahxAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-rMR8PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-LDTGBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-AML_26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-2EOrCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-PwYFtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Cjx_XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-2VBiTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-5ag4vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:09 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Hj8inL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:10 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ahY8fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:10 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-1sCY5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:10 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-_6EVkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:10 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ZcNKG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:10 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-weC9iW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:17:10 INFO - Running setup.py install for manifestparser 20:17:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:17:10 INFO - Running setup.py install for mozcrash 20:17:10 INFO - Running setup.py install for mozdebug 20:17:10 INFO - Running setup.py install for mozdevice 20:17:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Running setup.py install for mozfile 20:17:11 INFO - Running setup.py install for mozhttpd 20:17:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Running setup.py install for mozinfo 20:17:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Running setup.py install for mozInstall 20:17:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:17:11 INFO - Running setup.py install for mozleak 20:17:11 INFO - Running setup.py install for mozlog 20:17:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Running setup.py install for moznetwork 20:17:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Running setup.py install for mozprocess 20:17:12 INFO - Running setup.py install for mozprofile 20:17:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Running setup.py install for mozrunner 20:17:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Running setup.py install for mozscreenshot 20:17:12 INFO - Running setup.py install for moztest 20:17:12 INFO - Running setup.py install for mozversion 20:17:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:17:13 INFO - Cleaning up... 20:17:13 INFO - Return code: 0 20:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:17:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:13 INFO - 'HOME': '/Users/cltbld', 20:17:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:13 INFO - 'LOGNAME': 'cltbld', 20:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:13 INFO - 'MOZ_NO_REMOTE': '1', 20:17:13 INFO - 'NO_EM_RESTART': '1', 20:17:13 INFO - 'PAGER': '/bin/cat', 20:17:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:13 INFO - 'PWD': '/builds/slave/test', 20:17:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:13 INFO - 'SHELL': '/bin/bash', 20:17:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:13 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:13 INFO - 'USER': 'cltbld', 20:17:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:13 INFO - 'XPC_FLAGS': '0x0', 20:17:13 INFO - 'XPC_SERVICE_NAME': '0', 20:17:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:13 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-4Zj29o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:13 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-98lmR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:13 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-he50ZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:13 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-MUoZ4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:13 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Y6v0uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:13 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-NS63uO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ot_abd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-GIWsXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-S9KaDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ZXKGRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-mMl1On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-26vWh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-ATmyyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-gSPVWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-Yg5jBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:14 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)) 20:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:14 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-56uxbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:15 INFO - Running setup.py (path:/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/pip-YpNRiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:17:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:16 INFO - Downloading blessings-1.6.tar.gz 20:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:17:16 INFO - Installing collected packages: blessings 20:17:16 INFO - Running setup.py install for blessings 20:17:16 INFO - Successfully installed blessings 20:17:16 INFO - Cleaning up... 20:17:16 INFO - Return code: 0 20:17:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:17:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:17 INFO - Reading from file tmpfile_stdout 20:17:17 INFO - Current package versions: 20:17:17 INFO - blessings == 1.6 20:17:17 INFO - blobuploader == 1.2.4 20:17:17 INFO - docopt == 0.6.1 20:17:17 INFO - functools32 == 3.2.3-2 20:17:17 INFO - jsonschema == 2.5.1 20:17:17 INFO - manifestparser == 1.1 20:17:17 INFO - mozInstall == 1.13 20:17:17 INFO - mozcrash == 1.0 20:17:17 INFO - mozdebug == 0.1 20:17:17 INFO - mozdevice == 0.51 20:17:17 INFO - mozfile == 1.2 20:17:17 INFO - mozhttpd == 0.7 20:17:17 INFO - mozinfo == 0.10 20:17:17 INFO - mozleak == 0.1 20:17:17 INFO - mozlog == 3.4 20:17:17 INFO - moznetwork == 0.27 20:17:17 INFO - mozprocess == 0.25 20:17:17 INFO - mozprofile == 0.28 20:17:17 INFO - mozrunner == 6.13 20:17:17 INFO - mozscreenshot == 0.1 20:17:17 INFO - mozsystemmonitor == 0.3 20:17:17 INFO - moztest == 0.8 20:17:17 INFO - mozversion == 1.4 20:17:17 INFO - psutil == 3.1.1 20:17:17 INFO - requests == 1.2.3 20:17:17 INFO - wsgiref == 0.1.2 20:17:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:17 INFO - 'HOME': '/Users/cltbld', 20:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:17 INFO - 'LOGNAME': 'cltbld', 20:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:17 INFO - 'MOZ_NO_REMOTE': '1', 20:17:17 INFO - 'NO_EM_RESTART': '1', 20:17:17 INFO - 'PAGER': '/bin/cat', 20:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:17 INFO - 'PWD': '/builds/slave/test', 20:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:17 INFO - 'SHELL': '/bin/bash', 20:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:17 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:17 INFO - 'USER': 'cltbld', 20:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:17 INFO - 'XPC_FLAGS': '0x0', 20:17:17 INFO - 'XPC_SERVICE_NAME': '0', 20:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:17:17 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:18 INFO - Downloading marionette_driver-1.4.0.tar.gz 20:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 20:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:17:19 INFO - Installing collected packages: marionette-driver 20:17:19 INFO - Running setup.py install for marionette-driver 20:17:19 INFO - Successfully installed marionette-driver 20:17:19 INFO - Cleaning up... 20:17:19 INFO - Return code: 0 20:17:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f50768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd152c759c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, '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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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 20:17:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:17:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:19 INFO - 'HOME': '/Users/cltbld', 20:17:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:19 INFO - 'LOGNAME': 'cltbld', 20:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:19 INFO - 'MOZ_NO_REMOTE': '1', 20:17:19 INFO - 'NO_EM_RESTART': '1', 20:17:19 INFO - 'PAGER': '/bin/cat', 20:17:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:19 INFO - 'PWD': '/builds/slave/test', 20:17:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:19 INFO - 'SHELL': '/bin/bash', 20:17:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:19 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:19 INFO - 'USER': 'cltbld', 20:17:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:19 INFO - 'XPC_FLAGS': '0x0', 20:17:19 INFO - 'XPC_SERVICE_NAME': '0', 20:17:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:17:19 INFO - Cleaning up... 20:17:19 INFO - Return code: 0 20:17:19 INFO - Running post-action listener: _resource_record_post_action 20:17:19 INFO - Running post-action listener: _start_resource_monitoring 20:17:19 INFO - Starting resource monitoring. 20:17:19 INFO - [mozharness: 2017-06-14 03:17:19.536237Z] Finished create-virtualenv step (success) 20:17:19 INFO - [mozharness: 2017-06-14 03:17:19.537137Z] Running install step. 20:17:19 INFO - Running pre-action listener: _resource_record_pre_action 20:17:19 INFO - Running main action method: install 20:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:19 INFO - Reading from file tmpfile_stdout 20:17:19 INFO - Detecting whether we're running mozinstall >=1.0... 20:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:17:19 INFO - Reading from file tmpfile_stdout 20:17:19 INFO - Output received: 20:17:19 INFO - Usage: mozinstall [options] installer 20:17:19 INFO - Options: 20:17:19 INFO - -h, --help show this help message and exit 20:17:19 INFO - -d DEST, --destination=DEST 20:17:19 INFO - Directory to install application into. [default: 20:17:19 INFO - "/builds/slave/test"] 20:17:19 INFO - --app=APP Application being installed. [default: firefox] 20:17:19 INFO - mkdir: /builds/slave/test/build/application 20:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:17:41 INFO - Reading from file tmpfile_stdout 20:17:41 INFO - Output received: 20:17:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:17:41 INFO - Running post-action listener: _resource_record_post_action 20:17:41 INFO - [mozharness: 2017-06-14 03:17:41.953499Z] Finished install step (success) 20:17:41 INFO - [mozharness: 2017-06-14 03:17:41.953680Z] Running setup-mitmproxy step. 20:17:41 INFO - Running pre-action listener: _resource_record_pre_action 20:17:41 INFO - Running main action method: setup_mitmproxy 20:17:41 INFO - Skipping: mitmproxy is not required 20:17:41 INFO - Running post-action listener: _resource_record_post_action 20:17:41 INFO - [mozharness: 2017-06-14 03:17:41.954354Z] Finished setup-mitmproxy step (success) 20:17:41 INFO - [mozharness: 2017-06-14 03:17:41.954475Z] Running run-tests step. 20:17:41 INFO - Running pre-action listener: _resource_record_pre_action 20:17:41 INFO - Running main action method: run_tests 20:17:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:17:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:17:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:17:41 INFO - 2017-06-13 20:17:41.969 screenresolution[1628:8939] starting screenresolution argv=screenresolution get 20:17:41 INFO - 2017-06-13 20:17:41.971 screenresolution[1628:8939] Display 0: 1600x1200x32@60 20:17:41 INFO - 2017-06-13 20:17:41.977 screenresolution[1629:8942] starting screenresolution argv=screenresolution list 20:17:41 INFO - Available Modes on Display 0 20:17:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:17:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:17:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:17:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:17:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:17:42 INFO - Intel Iris: 20:17:42 INFO - Chipset Model: Intel Iris 20:17:42 INFO - Type: GPU 20:17:42 INFO - Bus: Built-In 20:17:42 INFO - VRAM (Dynamic, Max): 1536 MB 20:17:42 INFO - Vendor: Intel (0x8086) 20:17:42 INFO - Device ID: 0x0a2e 20:17:42 INFO - Revision ID: 0x0009 20:17:42 INFO - Displays: 20:17:42 INFO - Display: 20:17:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:17:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:17:42 INFO - Display Serial Number: 1600x1200 60 20:17:42 INFO - Main Display: Yes 20:17:42 INFO - Mirror: Off 20:17:42 INFO - Online: Yes 20:17:42 INFO - Rotation: Supported 20:17:42 INFO - Return code: 0 20:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:17:42 INFO - Python 2.7.3 20:17:42 INFO - Return code: 0 20:17:42 INFO - grabbing minidump binary from tooltool 20:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:17:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ca2d30>, '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 0x100c6ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c93be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:17:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:17:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:17:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:17:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:17:42 INFO - Return code: 0 20:17:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:17:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:17:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:17:42 INFO - ENV: RUST_BACKTRACE is now 1 20:17:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:17:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--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 20:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0277 --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 20:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SQQ7INSuVU/Render', 20:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:42 INFO - 'HOME': '/Users/cltbld', 20:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:42 INFO - 'LOGNAME': 'cltbld', 20:17:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:17:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:42 INFO - 'MOZ_NO_REMOTE': '1', 20:17:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:17:42 INFO - 'NO_EM_RESTART': '1', 20:17:42 INFO - 'PAGER': '/bin/cat', 20:17:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:42 INFO - 'PWD': '/builds/slave/test', 20:17:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:42 INFO - 'RUST_BACKTRACE': '1', 20:17:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:17:42 INFO - 'SHELL': '/bin/bash', 20:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZqhyQkhrpm/Listeners', 20:17:42 INFO - 'TMPDIR': '/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/', 20:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:42 INFO - 'USER': 'cltbld', 20:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:42 INFO - 'XPC_FLAGS': '0x0', 20:17:42 INFO - 'XPC_SERVICE_NAME': '0', 20:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0277', '--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 20:17:42 INFO - mozversion application_buildid: 20170613183502 20:17:42 INFO - mozversion application_changeset: b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c 20:17:42 INFO - mozversion application_display_name: Nightly 20:17:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:17:42 INFO - mozversion application_name: Firefox 20:17:42 INFO - mozversion application_remotingname: firefox 20:17:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:17:42 INFO - mozversion application_vendor: Mozilla 20:17:42 INFO - mozversion application_version: 56.0a1 20:17:42 INFO - mozversion platform_buildid: 20170613183502 20:17:42 INFO - mozversion platform_changeset: b266a8d8fd595b84a7d6218d7b8c6b7af0b5027c 20:17:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:17:42 INFO - mozversion platform_version: 56.0a1 20:17:42 INFO - using testdate: 1497410262 20:17:42 INFO - actual date: 1497410262 20:17:42 INFO - starting webserver on 'localhost:49226' 20:17:42 INFO - SUITE-START | Running 2 tests 20:17:42 INFO - TEST-START | dromaeo_css 20:17:42 INFO - operating with platform_type : mac_ 20:17:42 INFO - Initialising browser for dromaeo_css test... 20:17:42 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpsQyDTg/profile 20:17:42 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 20:18:16 INFO - TEST-INFO | 1639: killed by SIGTERM 20:18:16 INFO - Browser initialized. 20:18:16 INFO - Running cycle 1/1 for dromaeo_css test... 20:18:16 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmpsQyDTg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:18:18 INFO - PID 1648 | 2017-06-13 20:18:18.389 plugin-container[1649:9440] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983b, name = 'com.apple.tsm.portname' 20:18:18 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:18:18 INFO - PID 1648 | 2017-06-13 20:18:18.390 plugin-container[1649:9440] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0f, name = 'com.apple.CFPasteboardClient' 20:18:18 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:18:18 INFO - PID 1648 | 2017-06-13 20:18:18.390 plugin-container[1649:9440] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:18:19 INFO - PID 1648 | 2017-06-13 20:18:19.102 plugin-container[1650:9487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 20:18:19 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:18:19 INFO - PID 1648 | 2017-06-13 20:18:19.102 plugin-container[1650:9487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 20:18:19 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:18:19 INFO - PID 1648 | 2017-06-13 20:18:19.102 plugin-container[1650:9487] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:18:28 INFO - PID 1648 | RSS: Main: 253681664 20:18:28 INFO - PID 1648 | 20:21:31 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 20:21:34 INFO - PID 1648 | RSS: Main: 264314880 20:21:34 INFO - PID 1648 | 20:24:36 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 20:24:36 INFO - PID 1648 | RSS: Main: 274886656 20:24:36 INFO - PID 1648 | 20:27:38 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 20:27:39 INFO - PID 1648 | RSS: Main: 276623360 20:27:39 INFO - PID 1648 | 20:30:40 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 20:30:41 INFO - PID 1648 | RSS: Main: 277106688 20:30:41 INFO - PID 1648 | 20:33:44 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 20:33:44 INFO - PID 1648 | RSS: Main: 279416832 20:33:44 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 20:36:46 INFO - PID 1648 | RSS: Main: 279470080 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | __start_tp_report 20:36:46 INFO - PID 1648 | _x_x_mozilla_page_load 20:36:46 INFO - PID 1648 | _x_x_mozilla_page_load_details 20:36:46 INFO - PID 1648 | |i|pagename|runs| 20:36:46 INFO - PID 1648 | |0;dojo.html;3315.8891771256626;3449.154957035527;3590.5870824855133;3610.4945307656926;3621.2757448510306;5333.893322133555;6124.540659450537;6152.707931334414;6170.259568851744;6184.443400093991;6145.8630153421645;6153.077038444236;6193.504519638429;6262.373762623735;6292.307846136921;4162.83348666053;5283.784729512215;5405.864853378677;5484.561235101192;5547.306586676666;5499.202615620733;5544;5549.916751248735;5559.861003474925;5599.244102046233;4334.566543345672;5350.036993341197;5466.316710411199;5498.835034948958;5503.3395992480755;5361.5978801589645;5369.328833895763;5414.431484694129;5469.398366179717;5508.889822203533;5356.705381204032;5437.34751829779;5440.510354068113;5460.099083651233;5493;5663.320401551799;6186.783462578833;6248.50011999039;6255.12428260044;6256.15541901842;6603.438707709851;6611.570247933869;6647.60114393138;6652.767153149665;6657.633830139341;6133.365267548864;6217.751289948396;6418.422341989243;6727.629980351078;6754.155730533684;5843.6851708365275;6292.182016337938;6331.68341582919;6342.746290148342;6345;6199.628022318675;6905.723771049102;6909.136357955255;6910.516263861481;6922;5615.775368985195;6321.431071203614;6429.22849258092;6457.580257283262;6529.3470652935275;6573.3426657333885;6584.835379115465;6587.505937054644;6624.735010599522;6641.86716265672;5894.437973936911;6395.784800887816;6636.83407914806;6656.7670131545165;6671.566348187353;6659.600423974577;6661.566998145105;6662.700178491979;6684.364985326483;6710.731570737214;5203.895922081537;5218.739063046833;5271.393789714155;5282.102042652681;5343.145096784496;4737.502562230985;4758.286257061399;4820.807167713252;4877.341558889575;4879.2925025871045;4979.45226025137;6270.122182894398;6304.527160462983;6360.109584658151;6361.968190159112;6743.662816859138;6756.020377045399;6764.695588698521;6779.220389655253;6780.525363224626;6355.555111142268;6367.745290188341;6373.139626150503;6383.872322553616;6397.520185985976;5576.442355764472;5691.03252447085;5763.135529670583;5857.912131318034;5894.086416605433;6567.244766851778;6611.272759996396;6616.933830661632;6747;6923.446124310144;6064.878702425838;6692.297308782508;6699.799006029827;6731.629760363227;6750.932490675031;6888.655567221719;6893.517453778388;6896.172459304916;6911.412529935012;6921.826954326182;6639.170103737033;6642.535022548375;6643.402093811387;6647.670465906935;6651.966740166268;6726.697298621476;6751.392374686302;6759.864802704017;6798.844196486708;6816.897746533905;6348.968255158879;6349;6352.650604216812;6435.646039467874;6456.257530384066;6427.614343139427;6457.257415397102;6463.095166676763;6463.709133089116;6489.480841532761;5276.788928442819;5773.884522309614;5948.780499997577;6364.77723279683;6378.266499352634;6286.748530058747;6357.364263573606;6362.618242905441;6376.681165941777;6400.71196796136;6297.118403423616;6346.587471814502;6349.396807303298;6356.110144579855;6362.427381535684 20:36:46 INFO - PID 1648 | |1;ext.html;711.9750808721692;738.6602163005017;746.5296862976325;802.0736049862408;819.3158712475083;30270.243243918914;39151.60848391519;39183;39220;39317.606823931754;37963.620363796355;38455.230895382076;39474.605253947455;39526.40710389346;39596;8259.587020648974;8382.036065852413;8420.83158336836;8428;8453.535055571941;8187.26314631683;8286.212809783061;8307.584620768968;8310.33517318616;8334.291585215264;8221.588920553979;8274.627641756135;8306.335493160532;8309.086000539935;8312.04411492677;8243.8351232975;8244.134365891556;8250.422470427073;8258.587070646474;8269.25576698097;8088;8091.352691784702;8168.387370947144;8174.959125204396;8193.8361232775;37533.81233093817;37744.24511509755;37759;37762.62237377646;37830.054248643726;3593.2993066352074;3595.406757884946;3603.4414665726854;3628.4013137832226;3634.436662317345;30434.84782576095;30440.54339184914;30485.695143048728;30536.69463305361;30613.23466913345;3001.35470873761;3088.366884788613;3107.254258977852;3127.874885004597;3142.4343618148723;246581.53418465765;251030;253252.4674753266;301703.49148254335;310446;39078.804605977355;39193.80403097966;46928;47152.52847471481;47430.5256947433;50213.74893125511;50297.24554131691;50492.24261636078;50656.49343506592;50674;14227.14637121776;14285.571432856823;14380.85619143816;14409.42362305496;14410.42358305656;11570.247933884271;11725.765484690259;11737.765244695058;11738.295702257721;11738.647840564796;11814.05487560993;12195.878041219652;12976.805347919788;12983.870161298455;12994.740105198032;5279.208118782137;5283.286756287929;5291.814786482531;5293.258943747878;5310.097283461821;883.2580632268925;992.6773798515367;1004.6734811186394;1005.4520286443759;1023.155896385485;19585.510362240773;22864.542709145724;23256.53486930252;23390.766092338858;24312;969.4086607169621;1135.8750537440874;1147.143612405122;1153.798085335063;1165.9650210493699;13984.440622375194;15678.216089195723;16351.83648163527;16666.749998750027;16852.74720879188;333539;336575;336944;337395;337489;57523.424765751806;57910.42089579134;58117.41882581205;58206;58429.12356314659;1429.6354429620587;1512.0625212368402;1521.1329542161084;1526.2902750220978;1529.7552391617287;23251.953662084907;27318.1804545856;28145;30056.098317050524;30301.393972119993;37964.81017595005;40139.996500087735;40142;40262.1947561053;40392;228342;298234;298349;299071.5046424754;299635;34192.65807341895;34382.31235375329;34802;35547.46678799872;35561.64438355583;42847.57152428436;43670.34494482649;45165.32252016288;45716.77141614271;45724.8568785783;26318.342041449116;26542.734572654797;28865.85567072151;29213.415731685673;30242.24394390158;25524.872375638002;29651.703482965757;29775.553366699085;29903.700962990093;31172.844135779174;36198;37429.62570374261;37474.625253747115;37492.06269843276;37528.24943501169;27519.58720619152;29240.853795730884;30608.08175754731;30799.076027719206;32533.674663253063;23305;24061.398465038514;24640.753592464564;24671.876640616683;25023.624645629967 20:36:46 INFO - PID 1648 | |2;jquery.html;449.91676539840114;505.11856809866765;508.7865440923397;524.6013030097125;531.5906751801116;21973.450663733416;21998.45003874904;22452.55094898105;22856.085756569737;22892.084316627337;24081.63877541838;24559.75440245597;24863.62704559433;24906.626400604004;24935.50128997419;6487.610743355402;6635.867282654344;6682.699278532477;6688.3646053624825;6714.328567143271;6540.117084193621;6673.4661227101715;6677.265500794909;6679.565828221174;6685.5320127591085;5665.3768085510565;6304.077256436785;6311.337309582496;6334.3032266450655;6369.872602547946;5281.445448227938;6303.590266632654;6346.174997250351;6351.968240158816;6370;4848.733319667417;5406.83779486616;5812.564057695689;5832.591718579701;5858;29009.70990290091;29739.405211895642;29845.85077074601;30268.69731302681;30421.847890760626;2185.530111026137;2203.5482726041123;2235.0165926992236;2237.071615279657;2237.105157936822;19112.713309300234;19487.415377538553;19673.704894426453;19694;19922.203111875508;1911.5185731058402;2189.9671504927437;2245.5396643688005;2250.9212177573704;2263.19656521934;140147.29926350302;165728.17135914363;165850;168142;206778;13602.455901763818;13970.161790292615;14404.063735857137;15376.308066137051;15450.6137346565;20669.86315752618;20694.965251737354;20723.58552828935;20742.48143796387;20768.065437055368;9660.130588246951;9815;9985.700428987142;9989.80020399588;10023.548940297704;7505.774826755206;9078.773030674285;9105.726828195166;9129.269658427309;9169.954150229207;7412.96293518529;9277.907220927704;9300.209482193954;9311.813763724687;9321.64836098762;2597.9610305845426;2602.557565213918;2603.2580714496276;2627.908023219178;2739.904103356373;815.502543448496;930.4835816121982;943.6178347769164;999.9200063994863;1001.1490233301636;26000.34999125037;28708;29033.85483072571;29117;29292.560611590845;858.6479543387306;988.2538683294067;1014.518103900656;1032.9702135304162;1034.5448002878709;11051;11090;11361.829572556418;13743;14895.329710162852;543787;547342.8718654986;558268;560887.1955640278;568796;148788.256058719;150399.99200016158;153381.23309383605;153630;156413.2179339096;1154.2785758900686;1216.6410908782107;1219.1709637446445;1240.9007279417624;1242.3663931394874;22913.541729165656;24152.27127319241;24203.06375489992;24224;24273.150439734523;69266.30733692598;72799;72887.63556182185;73502.63248683722;74329;72812.90780638401;88280.1171988272;92252.61621075823;95188.52405737927;96259.55610665986;11503.424828758696;11754.23597466162;11826.290422574673;11913.22564033335;12013.819792702941;17867.821321786614;18216.180271887533;18370.54073648116;18435.26258949627;18743;14617.269136542918;14809.555713328617;15055.397784088515;15159.469418570297;15164.165970871709;11195.720106997065;13684.52104176349;13839.446422143;13938.30308484592;14308.356123974238;15891.523254302389;16002.759958600864;16021.118838463997;16035.518934431986;16390.672186555963;11872.228305160137;11994.64016079519;12050.517979281081;12172.208806427909;12182.14724969279;13549.59351219465;14601.853981460474;15161.317740701474;15273;15506.612334691272 20:36:46 INFO - PID 1648 | |3;mootools.html;1422.4096999745107;1466.435422362391;1480.2968589919797;1498.9250537473124;1510.6676531163148;11148.999999999989;13006.349682515873;13531.729365412686;13534.661633459169;13578.3210839458;9288;11077.11383089353;11394.031507321888;11631.941840290809;12025.879741202585;2678.5982102684607;2731.1533424638424;2734.4257705881787;2735.603337516059;2738.6850512191054;2656.9601455978177;2685.3555146764734;2693.676758788948;2710.0514819813106;2711.4170453352526;2650.09896635144;2672.839629622219;2676.330917270682;2684.5302072137397;2687.072959828856;2207.8896055197174;2305.827062970284;2640.234332043705;2648.708642049373;2660.2950218192195;2315.617923042696;2339.087755775249;2609.2433194373607;2703.2836298380944;2712.1185614675114;12773.48906043757;12864.935675321563;12882.935585322108;12889.548865789653;12890.677733056655;7028.156621205437;7247.347738703489;7325.120985481723;7328.890066649004;7332.56004639718;4471.530489298641;4756.476787553366;4845.055214233226;4867.36724225852;4869.245266983623;1390.242317936727;1391.4364682303683;1408.3943904121224;1437.7699568069058;1447.1100273331824;19572.412827615193;19617;19687.803121968882;19699.507512312164;19799.901000494905;6383.840403989938;6736.797896063126;6826.556273842184;6972.4073453757;7062.081929349151;16179.514614561582;16187.433439829549;16211;16250;16259.51221463358;7151.7854464366155;7549.50928189666;7591.164971853092;7595.354394876497;7615.238476152341;6548.9017664735065;6619.073329733841;6723.2519544917695;6753.425958793459;6807.18313802337;5119.078565858182;5224.50367215114;5282.286891269706;5317.9202311965355;5385.892282154335;1628.0463443867407;1655.4619748581517;1662.2187571841216;1675.7653928450027;1676.9832301676827;819.1685439279137;837.3552364679163;840.3277378097498;857.4598003257908;872.7207293666092;6895.392358999429;6970.0938877946555;7162.319579639926;7221.89167162493;7256.6734496947765;687.8933765266391;712.6508011074536;716.9784906452815;721.2499001038966;723.280336065618;3495.475678648223;4182.9581704182565;4262.211490874198;4282.914341713148;4491.317119961665;17087.743683844754;17908.46274611764;18056.90971545134;18057.006864622308;18062.90968545149;17318.826811731975;17513.387031454095;17784.822151778575;17990.370337038403;18090.90954545245;9181.495017774087;9203.907960920438;9295.256379489625;9344.95327523358;9407;7292.489525733148;7350;7656.961715191388;7695.769126926201;7893.052833659997;14207.218602976936;15484.225788710293;15739.842601573837;15889.443869464514;16015.199240038184;15254.389824406899;16027.679446411239;16268.593285167966;16363.59091022734;16372.754408684117;9659.661911833053;9984.450855203033;10511.894881051398;10673.786524269626;10747.677569672922;12053;12066.638000859988;12078.879211207775;12079.094067944763;12114.454849531616;9623.470709111067;9713;9862.358946668444;9898.653547125816;10012;9637.807243855224;9644.614215431584;9819.607215711292;9846.901530984598;9911.405315681082;9074.364794464323;9119.407238529475;9188.173064423967;9196.218321442484;9211.585478653342;6451.677416129269;6496.7726129585235;6863.656817159222;6881.243063263138;6972.965135174291;6751.831204219738;6802.97955306708;7048.189458212371;7072.752453664097;7110.537815042213 20:36:46 INFO - PID 1648 | |4;prototype.html;429.4095618524529;447.621759613127;455.71062375391625;469.40151307083465;470.52712024415456;5887.2346594942655;5908.852278693035;6471.352864713532;6506.674666266691;6524.836879078013;5523.177351474014;6178.598391104586;6368.267649220341;6427.839304017402;6498.447631951278;4762.952370476303;4764.833230836922;4779.426468823746;4796.520347965193;4806.206975848998;4778.542544523919;4780.426348838127;4797.9760101199445;4811.975940120312;4817.22924332107;4740.028294199681;4753.786079626408;4757.78676437508;4772.756804301973;4773.474917759044;3757.1170774867883;3892.9026774330587;4040.9595904040875;4554.6128579070655;4776.379070720802;4162.023039056454;4164.12553363794;4168.583141685837;4175.352820312856;4184.121334519755;6018.247719035121;6218.28489723683;6239.032949892774;6284.591501552384;6287.6856157192415;5611.018071837412;5645.01212287848;5658.886822263573;5661.46216109469;5667.744951477193;6243.940469170119;6826.863462730717;6834.352640204659;6845.691943862538;6859.2454829968665;4351.26028575143;4608.916904527467;4731.817045738565;4742.004179122388;4761.571458568746;6942.548734332303;7211.495195336329;7267.418606511466;7320.890186647204;7347.228541003171;3986.481757371523;4737.620990320766;4862.319275301461;4871.658983871095;4880.731559764176;4658.930116048262;4679.087577922341;4951.480094590088;4959.256111583291;4992.650514464025;5620.241267428926;5673.149027645886;5694.259746232963;5699.5725320600295;5710.2862142232225;6345.49236061114;6918.965405172942;6926.714184319163;6938.687759050855;6966.582005079711;1528.9388424462898;1616.822149563547;1680.0507713142035;1681.8318168183084;1686.6169740812416;1085.4843949124104;1127.3517727306833;1133.880942501042;1136.8351589019546;1140.1790710688292;5424.349078110652;5545.61180717354;6184.196054512884;6271.982414068818;6332.905006424908;5590.217369568264;6423.935760642427;7038.436925045984;7110.466714996396;7436.070491188601;5683.60214784961;6380.7447702091395;6411.967940160363;6434.774782882577;6437.388448097423;3344.39800835847;3527.4073555936366;3540.5574303212097;3631.673149416565;3633.5662798655544;6688.297728738413;7166.498345115792;7224.422046236288;7274.818129546804;7284.635768211569;6479.7408103675325;6525.955847064447;7167.3549380556;7250.6374681265725;7301.963490182515;6084.847878802977;6241.34465881085;6671.833204169838;6989.741846467685;7310.305520975498;5650.672092058281;7048.471364647569;7058.364747172779;7097.893531597134;7250.093738282715;5813.738381772746;6784.812657784966;6911;6965;6971.128608923885;5923.548730560868;5998.34018258;6885.449164066963;7247.4926755128745;7372.189059203591;4716.976415117902;5092.111572890067;5304.151335786256;5406.972965135149;5416.918746218731;6963.512554121163;7042.823929401601;7081.68132434052;7084.964575177091;7087.3267039631155;5693.715314234189;6782.660866956534;6803.040677305388;6816.2842901496615;6846.021980438525;5556.499915007668;6472.902906456313;6796.830079248059;6825.453963682993;6848.8972665409065;5318.97340513295;6637.1703537057865;6656.301088385843;6672.866542669022;6685.699143538455;6155.753769849156;6592.604443733391;6766.699322230465;6771.221309549268;6772.762953296611;6263.311035785969;6394.744210231539;6499.967500162469;6549.574277671936;6609.959418667467 20:36:46 INFO - PID 1648 | |5;yui.html;786.5162924801247;830.236182711905;838.2832678060262;859.2395729779146;874.147705986663;17066;17117.97292162468;17256.74114888278;17356.47930562085;17560.736588951175;9775.755606109851;13225.20648761075;13466.461341546326;16175.757363639555;16867.24097415616;958.2094771813247;960.6349587156906;974.8830140383164;981.2885657898003;981.3915372469079;981.7349315684761;1011.0294117647068;1011.8279892418307;1012.0385633648027;1013.5337744637476;1054.1408751867205;1065.9413732244723;1068.6206396729165;1069.7004838645153;1071.2982996137541;933.7385532051037;995.1740055753708;1060.2737125069275;1066.1577353890418;1074.5862842805498;642.7075680565332;770.4876257288896;774.411447300056;774.7133560582564;778.1051790441038;16830.410935617194;17262.913685431493;17336.479905602853;17457.03986280754;17458.825411745845;15697.450589229436;17505.7374139388;17685.823141768677;17962.640747185113;18175.272989080422;14403.711925761427;14613.92693036528;14728.116313021254;14799;14855.628609284746;3248.31785325082;3537.7523573349877;3579.552555930509;3602.9279414411826;3606.296772129436;147070.26464867714;152236.47763522333;155846.6623000656;158710.20644896818;158740;12879.678008049874;13512.324383780771;13615.18308901469;13631.182129072287;13710.794338084937;26090.347741306618;26579.33551661224;26751.732482674925;27001;27025;7879.172686867911;8006.239407256301;8020.35837133028;8029.759107226676;8063.314618257514;3761.191343861028;3781.9243615128094;3820.465134881119;3833.8274777635074;3836.7889766063136;4592.219322715079;4618.630509559227;4644.326572646947;4673.579377856009;4681.461631912373;926.4348747264163;969.7381706939087;981.4405788700373;983.4836710726926;986.7681094942683;644.432899048834;649.3052433895687;660.1286302081206;673.6362364323211;681.0057316318231;10957.17821163416;11885.038968570261;12389.318587477595;12575.245485270912;12601.306928119042;626.37988391493;646.747768370336;651.739304278283;670.1656437734954;677.3734295776387;5230.895382092337;5540.529055030368;5587.608867379245;5751.4823665869435;5826;124873;135181.3240933789;137446;145626.5437345613;146400;100432;102840;106638.46680766546;106653.46673266584;107930;96661.51669241609;102018;102382;103027.4848625752;106371;10991.560337586407;11476.902410507657;11715.414229288672;12076.275423474619;12931.5473958411;44440.33339499845;45546.31680524861;48288.55134345975;49352.76618084576;49565;62749.68625156845;62824.05763913454;66034;66257;67578.66210668915;16584.585385365463;16851.578710532336;17133.143342833057;17334.82665173332;17425.912870435568;15661.530154095395;17876.731849022533;18367.989760563298;18370.908145459187;18424.35514756977;16874.578135546708;16901.661966760843;17101.57246068858;17440.82559174392;17581.648367032332;16425.425110121087;16740.24668889927;17034.48896533106;17361.30554777845;17466.563335916195;16344.264508096927;16533.256003479633;16540.338386464406;16803.907745996472;16810.915945420686;11136.38749868765;11476.770464590494;11565.710857228636;12096.274223546616;12205.20666156697;10829.278798617805;12351.69120771988;12627.684307892376;13047.151935124186;13260.071794974645 20:36:46 INFO - PID 1648 | __end_tp_report 20:36:46 INFO - PID 1648 | __start_cc_report 20:36:46 INFO - PID 1648 | _x_x_mozilla_cycle_collect,465 20:36:46 INFO - PID 1648 | __end_cc_report 20:36:46 INFO - PID 1648 | __startTimestamp1497411406747__endTimestamp 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | ------- Summary: start ------- 20:36:46 INFO - PID 1648 | Number of tests: 6 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6087.08 Median:6349.00 stddev:741.67 (11.7%) stddev-sans-first:711.37 20:36:46 INFO - PID 1648 | Values: 3315.9 3449.2 3590.6 3610.5 3621.3 5333.9 6124.5 6152.7 6170.3 6184.4 6145.9 6153.1 6193.5 6262.4 6292.3 4162.8 5283.8 5405.9 5484.6 5547.3 5499.2 5544.0 5549.9 5559.9 5599.2 4334.6 5350.0 5466.3 5498.8 5503.3 5361.6 5369.3 5414.4 5469.4 5508.9 5356.7 5437.3 5440.5 5460.1 5493.0 5663.3 6186.8 6248.5 6255.1 6256.2 6603.4 6611.6 6647.6 6652.8 6657.6 6133.4 6217.8 6418.4 6727.6 6754.2 5843.7 6292.2 6331.7 6342.7 6345.0 6199.6 6905.7 6909.1 6910.5 6922.0 5615.8 6321.4 6429.2 6457.6 6529.3 6573.3 6584.8 6587.5 6624.7 6641.9 5894.4 6395.8 6636.8 6656.8 6671.6 6659.6 6661.6 6662.7 6684.4 6710.7 5203.9 5218.7 5271.4 5282.1 5343.1 4737.5 4758.3 4820.8 4877.3 4879.3 4979.5 6270.1 6304.5 6360.1 6362.0 6743.7 6756.0 6764.7 6779.2 6780.5 6355.6 6367.7 6373.1 6383.9 6397.5 5576.4 5691.0 5763.1 5857.9 5894.1 6567.2 6611.3 6616.9 6747.0 6923.4 6064.9 6692.3 6699.8 6731.6 6750.9 6888.7 6893.5 6896.2 6911.4 6921.8 6639.2 6642.5 6643.4 6647.7 6652.0 6726.7 6751.4 6759.9 6798.8 6816.9 6349.0 6349.0 6352.7 6435.6 6456.3 6427.6 6457.3 6463.1 6463.7 6489.5 5276.8 5773.9 5948.8 6364.8 6378.3 6286.7 6357.4 6362.6 6376.7 6400.7 6297.1 6346.6 6349.4 6356.1 6362.4 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:45056.78 Median:35174.73 stddev:78739.01 (223.9%) stddev-sans-first:78889.50 20:36:46 INFO - PID 1648 | Values: 712.0 738.7 746.5 802.1 819.3 30270.2 39151.6 39183.0 39220.0 39317.6 37963.6 38455.2 39474.6 39526.4 39596.0 8259.6 8382.0 8420.8 8428.0 8453.5 8187.3 8286.2 8307.6 8310.3 8334.3 8221.6 8274.6 8306.3 8309.1 8312.0 8243.8 8244.1 8250.4 8258.6 8269.3 8088.0 8091.4 8168.4 8175.0 8193.8 37533.8 37744.2 37759.0 37762.6 37830.1 3593.3 3595.4 3603.4 3628.4 3634.4 30434.8 30440.5 30485.7 30536.7 30613.2 3001.4 3088.4 3107.3 3127.9 3142.4 246581.5 251030.0 253252.5 301703.5 310446.0 39078.8 39193.8 46928.0 47152.5 47430.5 50213.7 50297.2 50492.2 50656.5 50674.0 14227.1 14285.6 14380.9 14409.4 14410.4 11570.2 11725.8 11737.8 11738.3 11738.6 11814.1 12195.9 12976.8 12983.9 12994.7 5279.2 5283.3 5291.8 5293.3 5310.1 883.3 992.7 1004.7 1005.5 1023.2 19585.5 22864.5 23256.5 23390.8 24312.0 969.4 1135.9 1147.1 1153.8 1166.0 13984.4 15678.2 16351.8 16666.7 16852.7 333539.0 336575.0 336944.0 337395.0 337489.0 57523.4 57910.4 58117.4 58206.0 58429.1 1429.6 1512.1 1521.1 1526.3 1529.8 23252.0 27318.2 28145.0 30056.1 30301.4 37964.8 40140.0 40142.0 40262.2 40392.0 228342.0 298234.0 298349.0 299071.5 299635.0 34192.7 34382.3 34802.0 35547.5 35561.6 42847.6 43670.3 45165.3 45716.8 45724.9 26318.3 26542.7 28865.9 29213.4 30242.2 25524.9 29651.7 29775.6 29903.7 31172.8 36198.0 37429.6 37474.6 37492.1 37528.2 27519.6 29240.9 30608.1 30799.1 32533.7 23305.0 24061.4 24640.8 24671.9 25023.6 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:39074.94 Median:23497.59 stddev:95462.13 (406.3%) stddev-sans-first:95685.88 20:36:46 INFO - PID 1648 | Values: 449.9 505.1 508.8 524.6 531.6 21973.5 21998.5 22452.6 22856.1 22892.1 24081.6 24559.8 24863.6 24906.6 24935.5 6487.6 6635.9 6682.7 6688.4 6714.3 6540.1 6673.5 6677.3 6679.6 6685.5 5665.4 6304.1 6311.3 6334.3 6369.9 5281.4 6303.6 6346.2 6352.0 6370.0 4848.7 5406.8 5812.6 5832.6 5858.0 29009.7 29739.4 29845.9 30268.7 30421.8 2185.5 2203.5 2235.0 2237.1 2237.1 19112.7 19487.4 19673.7 19694.0 19922.2 1911.5 2190.0 2245.5 2250.9 2263.2 140147.3 165728.2 165850.0 168142.0 206778.0 13602.5 13970.2 14404.1 15376.3 15450.6 20669.9 20695.0 20723.6 20742.5 20768.1 9660.1 9815.0 9985.7 9989.8 10023.5 7505.8 9078.8 9105.7 9129.3 9170.0 7413.0 9277.9 9300.2 9311.8 9321.6 2598.0 2602.6 2603.3 2627.9 2739.9 815.5 930.5 943.6 999.9 1001.1 26000.3 28708.0 29033.9 29117.0 29292.6 858.6 988.3 1014.5 1033.0 1034.5 11051.0 11090.0 11361.8 13743.0 14895.3 543787.0 547342.9 558268.0 560887.2 568796.0 148788.3 150400.0 153381.2 153630.0 156413.2 1154.3 1216.6 1219.2 1240.9 1242.4 22913.5 24152.3 24203.1 24224.0 24273.2 69266.3 72799.0 72887.6 73502.6 74329.0 72812.9 88280.1 92252.6 95188.5 96259.6 11503.4 11754.2 11826.3 11913.2 12013.8 17867.8 18216.2 18370.5 18435.3 18743.0 14617.3 14809.6 15055.4 15159.5 15164.2 11195.7 13684.5 13839.4 13938.3 14308.4 15891.5 16002.8 16021.1 16035.5 16390.7 11872.2 11994.6 12050.5 12172.2 12182.1 13549.6 14601.9 15161.3 15273.0 15506.6 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:8064.83 Median:2735.01 stddev:5347.97 (195.5%) stddev-sans-first:5339.68 20:36:46 INFO - PID 1648 | Values: 1422.4 1466.4 1480.3 1498.9 1510.7 11149.0 13006.3 13531.7 13534.7 13578.3 9288.0 11077.1 11394.0 11631.9 12025.9 2678.6 2731.2 2734.4 2735.6 2738.7 2657.0 2685.4 2693.7 2710.1 2711.4 2650.1 2672.8 2676.3 2684.5 2687.1 2207.9 2305.8 2640.2 2648.7 2660.3 2315.6 2339.1 2609.2 2703.3 2712.1 12773.5 12864.9 12882.9 12889.5 12890.7 7028.2 7247.3 7325.1 7328.9 7332.6 4471.5 4756.5 4845.1 4867.4 4869.2 1390.2 1391.4 1408.4 1437.8 1447.1 19572.4 19617.0 19687.8 19699.5 19799.9 6383.8 6736.8 6826.6 6972.4 7062.1 16179.5 16187.4 16211.0 16250.0 16259.5 7151.8 7549.5 7591.2 7595.4 7615.2 6548.9 6619.1 6723.3 6753.4 6807.2 5119.1 5224.5 5282.3 5317.9 5385.9 1628.0 1655.5 1662.2 1675.8 1677.0 819.2 837.4 840.3 857.5 872.7 6895.4 6970.1 7162.3 7221.9 7256.7 687.9 712.7 717.0 721.2 723.3 3495.5 4183.0 4262.2 4282.9 4491.3 17087.7 17908.5 18056.9 18057.0 18062.9 17318.8 17513.4 17784.8 17990.4 18090.9 9181.5 9203.9 9295.3 9345.0 9407.0 7292.5 7350.0 7657.0 7695.8 7893.1 14207.2 15484.2 15739.8 15889.4 16015.2 15254.4 16027.7 16268.6 16363.6 16372.8 9659.7 9984.5 10511.9 10673.8 10747.7 12053.0 12066.6 12078.9 12079.1 12114.5 9623.5 9713.0 9862.4 9898.7 10012.0 9637.8 9644.6 9819.6 9846.9 9911.4 9074.4 9119.4 9188.2 9196.2 9211.6 6451.7 6496.8 6863.7 6881.2 6973.0 6751.8 6803.0 7048.2 7072.8 7110.5 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5529.05 Median:6120.30 stddev:1689.05 (27.6%) stddev-sans-first:1649.85 20:36:46 INFO - PID 1648 | Values: 429.4 447.6 455.7 469.4 470.5 5887.2 5908.9 6471.4 6506.7 6524.8 5523.2 6178.6 6368.3 6427.8 6498.4 4763.0 4764.8 4779.4 4796.5 4806.2 4778.5 4780.4 4798.0 4812.0 4817.2 4740.0 4753.8 4757.8 4772.8 4773.5 3757.1 3892.9 4041.0 4554.6 4776.4 4162.0 4164.1 4168.6 4175.4 4184.1 6018.2 6218.3 6239.0 6284.6 6287.7 5611.0 5645.0 5658.9 5661.5 5667.7 6243.9 6826.9 6834.4 6845.7 6859.2 4351.3 4608.9 4731.8 4742.0 4761.6 6942.5 7211.5 7267.4 7320.9 7347.2 3986.5 4737.6 4862.3 4871.7 4880.7 4658.9 4679.1 4951.5 4959.3 4992.7 5620.2 5673.1 5694.3 5699.6 5710.3 6345.5 6919.0 6926.7 6938.7 6966.6 1528.9 1616.8 1680.1 1681.8 1686.6 1085.5 1127.4 1133.9 1136.8 1140.2 5424.3 5545.6 6184.2 6272.0 6332.9 5590.2 6423.9 7038.4 7110.5 7436.1 5683.6 6380.7 6412.0 6434.8 6437.4 3344.4 3527.4 3540.6 3631.7 3633.6 6688.3 7166.5 7224.4 7274.8 7284.6 6479.7 6526.0 7167.4 7250.6 7302.0 6084.8 6241.3 6671.8 6989.7 7310.3 5650.7 7048.5 7058.4 7097.9 7250.1 5813.7 6784.8 6911.0 6965.0 6971.1 5923.5 5998.3 6885.4 7247.5 7372.2 4717.0 5092.1 5304.2 5407.0 5416.9 6963.5 7042.8 7081.7 7085.0 7087.3 5693.7 6782.7 6803.0 6816.3 6846.0 5556.5 6472.9 6796.8 6825.5 6848.9 5319.0 6637.2 6656.3 6672.9 6685.7 6155.8 6592.6 6766.7 6771.2 6772.8 6263.3 6394.7 6500.0 6549.6 6610.0 20:36:46 INFO - PID 1648 | 20:36:46 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:24993.37 Median:17433.37 stddev:38416.27 (220.4%) stddev-sans-first:38481.05 20:36:46 INFO - PID 1648 | Values: 786.5 830.2 838.3 859.2 874.1 17066.0 17118.0 17256.7 17356.5 17560.7 9775.8 13225.2 13466.5 16175.8 16867.2 958.2 960.6 974.9 981.3 981.4 981.7 1011.0 1011.8 1012.0 1013.5 1054.1 1065.9 1068.6 1069.7 1071.3 933.7 995.2 1060.3 1066.2 1074.6 642.7 770.5 774.4 774.7 778.1 16830.4 17262.9 17336.5 17457.0 17458.8 15697.5 17505.7 17685.8 17962.6 18175.3 14403.7 14613.9 14728.1 14799.0 14855.6 3248.3 3537.8 3579.6 3602.9 3606.3 147070.3 152236.5 155846.7 158710.2 158740.0 12879.7 13512.3 13615.2 13631.2 13710.8 26090.3 26579.3 26751.7 27001.0 27025.0 7879.2 8006.2 8020.4 8029.8 8063.3 3761.2 3781.9 3820.5 3833.8 3836.8 4592.2 4618.6 4644.3 4673.6 4681.5 926.4 969.7 981.4 983.5 986.8 644.4 649.3 660.1 673.6 681.0 10957.2 11885.0 12389.3 12575.2 12601.3 626.4 646.7 651.7 670.2 677.4 5230.9 5540.5 5587.6 5751.5 5826.0 124873.0 135181.3 137446.0 145626.5 146400.0 100432.0 102840.0 106638.5 106653.5 107930.0 96661.5 102018.0 102382.0 103027.5 106371.0 10991.6 11476.9 11715.4 12076.3 12931.5 44440.3 45546.3 48288.6 49352.8 49565.0 62749.7 62824.1 66034.0 66257.0 67578.7 16584.6 16851.6 17133.1 17334.8 17425.9 15661.5 17876.7 18368.0 18370.9 18424.4 16874.6 16901.7 17101.6 17440.8 17581.6 16425.4 16740.2 17034.5 17361.3 17466.6 16344.3 16533.3 16540.3 16803.9 16810.9 11136.4 11476.8 11565.7 12096.3 12205.2 10829.3 12351.7 12627.7 13047.2 13260.1 20:36:46 INFO - PID 1648 | -------- Summary: end -------- 20:36:46 INFO - PID 1648 | 20:36:47 INFO - TEST-INFO | 1648: exit 0 20:36:47 INFO - TEST-OK | dromaeo_css | took 1144748ms 20:36:47 INFO - TEST-START | kraken 20:36:47 INFO - operating with platform_type : mac_ 20:36:47 INFO - Initialising browser for kraken test... 20:36:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp1NRRT5/profile 20:36:47 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 20:37:20 INFO - TEST-INFO | 1658: killed by SIGTERM 20:37:20 INFO - Browser initialized. 20:37:20 INFO - Running cycle 1/1 for kraken test... 20:37:20 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/T/tmp1NRRT5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:37:22 INFO - PID 1662 | 2017-06-13 20:37:22.643 plugin-container[1663:12061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 20:37:22 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:37:22 INFO - PID 1662 | 2017-06-13 20:37:22.643 plugin-container[1663:12061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481f, name = 'com.apple.CFPasteboardClient' 20:37:22 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:37:22 INFO - PID 1662 | 2017-06-13 20:37:22.643 plugin-container[1663:12061] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:37:23 INFO - PID 1662 | 2017-06-13 20:37:23.470 plugin-container[1664:12109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 20:37:23 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:37:23 INFO - PID 1662 | 2017-06-13 20:37:23.471 plugin-container[1664:12109] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1b, name = 'com.apple.CFPasteboardClient' 20:37:23 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:37:23 INFO - PID 1662 | 2017-06-13 20:37:23.471 plugin-container[1664:12109] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:37:32 INFO - PID 1662 | RSS: Main: 254439424 20:37:32 INFO - PID 1662 | 20:37:35 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 20:37:36 INFO - PID 1662 | RSS: Main: 266649600 20:37:36 INFO - PID 1662 | 20:37:39 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 20:37:39 INFO - PID 1662 | RSS: Main: 276467712 20:37:39 INFO - PID 1662 | 20:37:43 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 20:37:43 INFO - PID 1662 | RSS: Main: 279412736 20:37:43 INFO - PID 1662 | 20:37:45 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 20:37:46 INFO - PID 1662 | RSS: Main: 281006080 20:37:46 INFO - PID 1662 | 20:37:48 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:37:49 INFO - PID 1662 | RSS: Main: 281595904 20:37:49 INFO - PID 1662 | 20:37:51 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 20:37:52 INFO - PID 1662 | RSS: Main: 286613504 20:37:52 INFO - PID 1662 | 20:37:55 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 20:37:56 INFO - PID 1662 | RSS: Main: 286093312 20:37:56 INFO - PID 1662 | 20:37:59 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 20:38:00 INFO - PID 1662 | RSS: Main: 286838784 20:38:00 INFO - PID 1662 | 20:38:01 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:38:02 INFO - PID 1662 | RSS: Main: 286334976 20:38:02 INFO - PID 1662 | 20:38:04 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:38:05 INFO - PID 1662 | RSS: Main: 287686656 20:38:05 INFO - PID 1662 | 20:38:07 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:38:07 INFO - PID 1662 | RSS: Main: 287510528 20:38:07 INFO - PID 1662 | 20:38:10 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:38:11 INFO - PID 1662 | RSS: Main: 287617024 20:38:11 INFO - PID 1662 | 20:38:14 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:38:15 INFO - PID 1662 | RSS: Main: 287727616 20:38:15 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 20:38:17 INFO - PID 1662 | RSS: Main: 289505280 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | __start_tp_report 20:38:17 INFO - PID 1662 | _x_x_mozilla_page_load 20:38:17 INFO - PID 1662 | _x_x_mozilla_page_load_details 20:38:17 INFO - PID 1662 | |i|pagename|runs| 20:38:17 INFO - PID 1662 | |0;ai-astar;104;116;117;126;118;115;118;114;115;119 20:38:17 INFO - PID 1662 | |1;audio-beat-detection;132;118;149;166;138;173;134;110;156;136 20:38:17 INFO - PID 1662 | |2;audio-dft;203;199;198;213;220;237;270;251;199;196 20:38:17 INFO - PID 1662 | |3;audio-fft;70;86;70;130;76;63;73;63;103;61 20:38:17 INFO - PID 1662 | |4;audio-oscillator;70;69;112;69;73;79;111;70;78;128 20:38:17 INFO - PID 1662 | |5;imaging-gaussian-blur;91;91;91;90;90;92;91;90;90;90 20:38:17 INFO - PID 1662 | |6;imaging-darkroom;98;98;124;94;152;122;98;97;108;98 20:38:17 INFO - PID 1662 | |7;imaging-desaturate;142;149;115;83;148;127;139;112;92;92 20:38:17 INFO - PID 1662 | |8;json-parse-financial;50;50;52;53;51;54;53;51;66;81 20:38:17 INFO - PID 1662 | |9;json-stringify-tinderbox;54;65;85;87;63;74;91;85;58;56 20:38:17 INFO - PID 1662 | |10;stanford-crypto-aes;82;82;79;81;82;80;84;83;82;83 20:38:17 INFO - PID 1662 | |11;stanford-crypto-ccm;139;154;152;140;141;140;161;159;150;194 20:38:17 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;179;179;179;181;235;175;181;176;174;187 20:38:17 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;68;64;67;70;79;95;98;85;65;66 20:38:17 INFO - PID 1662 | __end_tp_report 20:38:17 INFO - PID 1662 | __start_cc_report 20:38:17 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1157 20:38:17 INFO - PID 1662 | __end_cc_report 20:38:17 INFO - PID 1662 | __startTimestamp1497411497338__endTimestamp 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | ------- Summary: start ------- 20:38:17 INFO - PID 1662 | Number of tests: 14 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.50 stddev:5.45 (4.6%) stddev-sans-first:3.57 20:38:17 INFO - PID 1662 | Values: 104.0 116.0 117.0 126.0 118.0 115.0 118.0 114.0 115.0 119.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:143.50 stddev:19.98 (13.9%) stddev-sans-first:20.91 20:38:17 INFO - PID 1662 | Values: 132.0 118.0 149.0 166.0 138.0 173.0 134.0 110.0 156.0 136.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:218.60 Median:216.50 stddev:25.84 (11.9%) stddev-sans-first:26.79 20:38:17 INFO - PID 1662 | Values: 203.0 199.0 198.0 213.0 220.0 237.0 270.0 251.0 199.0 196.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:79.50 Median:70.00 stddev:21.77 (31.1%) stddev-sans-first:22.82 20:38:17 INFO - PID 1662 | Values: 70.0 86.0 70.0 130.0 76.0 63.0 73.0 63.0 103.0 61.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:70.00 stddev:22.20 (31.7%) stddev-sans-first:22.79 20:38:17 INFO - PID 1662 | Values: 70.0 69.0 112.0 69.0 73.0 79.0 111.0 70.0 78.0 128.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:91.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 20:38:17 INFO - PID 1662 | Values: 91.0 91.0 91.0 90.0 90.0 92.0 91.0 90.0 90.0 90.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:97.50 stddev:18.55 (19.0%) stddev-sans-first:19.25 20:38:17 INFO - PID 1662 | Values: 98.0 98.0 124.0 94.0 152.0 122.0 98.0 97.0 108.0 98.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:119.90 Median:148.50 stddev:24.82 (16.7%) stddev-sans-first:25.01 20:38:17 INFO - PID 1662 | Values: 142.0 149.0 115.0 83.0 148.0 127.0 139.0 112.0 92.0 92.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:53.00 stddev:9.92 (18.7%) stddev-sans-first:10.27 20:38:17 INFO - PID 1662 | Values: 50.0 50.0 52.0 53.0 51.0 54.0 53.0 51.0 66.0 81.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:79.50 stddev:14.27 (18.0%) stddev-sans-first:13.61 20:38:17 INFO - PID 1662 | Values: 54.0 65.0 85.0 87.0 63.0 74.0 91.0 85.0 58.0 56.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.48 (1.8%) stddev-sans-first:1.56 20:38:17 INFO - PID 1662 | Values: 82.0 82.0 79.0 81.0 82.0 80.0 84.0 83.0 82.0 83.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:153.00 stddev:16.57 (10.8%) stddev-sans-first:16.78 20:38:17 INFO - PID 1662 | Values: 139.0 154.0 152.0 140.0 141.0 140.0 161.0 159.0 150.0 194.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:180.00 stddev:18.09 (10.0%) stddev-sans-first:19.07 20:38:17 INFO - PID 1662 | Values: 179.0 179.0 179.0 181.0 235.0 175.0 181.0 176.0 174.0 187.0 20:38:17 INFO - PID 1662 | 20:38:17 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:74.50 stddev:12.82 (17.2%) stddev-sans-first:13.30 20:38:17 INFO - PID 1662 | Values: 68.0 64.0 67.0 70.0 79.0 95.0 98.0 85.0 65.0 66.0 20:38:17 INFO - PID 1662 | -------- Summary: end -------- 20:38:17 INFO - PID 1662 | 20:38:18 INFO - TEST-INFO | 1662: exit 0 20:38:18 INFO - TEST-OK | kraken | took 90857ms 20:38:18 INFO - SUITE-END | took 1235s 20:38:18 INFO - Completed test suite (00:20:35) 20:38:18 INFO - javascript benchmark 20:38:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8291.177404426575, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.8891771256626, 3449.154957035527, 3590.5870824855133, 3610.4945307656926, 3621.2757448510306, 5333.893322133555, 6124.540659450537, 6152.707931334414, 6170.259568851744, 6184.443400093991, 6145.8630153421645, 6153.077038444236, 6193.504519638429, 6262.373762623735, 6292.307846136921, 4162.83348666053, 5283.784729512215, 5405.864853378677, 5484.561235101192, 5547.306586676666, 5499.202615620733, 5544.0, 5549.916751248735, 5559.861003474925, 5599.244102046233, 4334.566543345672, 5350.036993341197, 5466.316710411199, 5498.835034948958, 5503.3395992480755, 5361.5978801589645, 5369.328833895763, 5414.431484694129, 5469.398366179717, 5508.889822203533, 5356.705381204032, 5437.34751829779, 5440.510354068113, 5460.099083651233, 5493.0, 5663.320401551799, 6186.783462578833, 6248.50011999039, 6255.12428260044, 6256.15541901842, 6603.438707709851, 6611.570247933869, 6647.60114393138, 6652.767153149665, 6657.633830139341, 6133.365267548864, 6217.751289948396, 6418.422341989243, 6727.629980351078, 6754.155730533684, 5843.6851708365275, 6292.182016337938, 6331.68341582919, 6342.746290148342, 6345.0, 6199.628022318675, 6905.723771049102, 6909.136357955255, 6910.516263861481, 6922.0, 5615.775368985195, 6321.431071203614, 6429.22849258092, 6457.580257283262, 6529.3470652935275, 6573.3426657333885, 6584.835379115465, 6587.505937054644, 6624.735010599522, 6641.86716265672, 5894.437973936911, 6395.784800887816, 6636.83407914806, 6656.7670131545165, 6671.566348187353, 6659.600423974577, 6661.566998145105, 6662.700178491979, 6684.364985326483, 6710.731570737214, 5203.895922081537, 5218.739063046833, 5271.393789714155, 5282.102042652681, 5343.145096784496, 4737.502562230985, 4758.286257061399, 4820.807167713252, 4877.341558889575, 4879.2925025871045, 4979.45226025137, 6270.122182894398, 6304.527160462983, 6360.109584658151, 6361.968190159112, 6743.662816859138, 6756.020377045399, 6764.695588698521, 6779.220389655253, 6780.525363224626, 6355.555111142268, 6367.745290188341, 6373.139626150503, 6383.872322553616, 6397.520185985976, 5576.442355764472, 5691.03252447085, 5763.135529670583, 5857.912131318034, 5894.086416605433, 6567.244766851778, 6611.272759996396, 6616.933830661632, 6747.0, 6923.446124310144, 6064.878702425838, 6692.297308782508, 6699.799006029827, 6731.629760363227, 6750.932490675031, 6888.655567221719, 6893.517453778388, 6896.172459304916, 6911.412529935012, 6921.826954326182, 6639.170103737033, 6642.535022548375, 6643.402093811387, 6647.670465906935, 6651.966740166268, 6726.697298621476, 6751.392374686302, 6759.864802704017, 6798.844196486708, 6816.897746533905, 6348.968255158879, 6349.0, 6352.650604216812, 6435.646039467874, 6456.257530384066, 6427.614343139427, 6457.257415397102, 6463.095166676763, 6463.709133089116, 6489.480841532761, 5276.788928442819, 5773.884522309614, 5948.780499997577, 6364.77723279683, 6378.266499352634, 6286.748530058747, 6357.364263573606, 6362.618242905441, 6376.681165941777, 6400.71196796136, 6297.118403423616, 6346.587471814502, 6349.396807303298, 6356.110144579855, 6362.427381535684], "value": 6038.737490998795, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.9750808721692, 738.6602163005017, 746.5296862976325, 802.0736049862408, 819.3158712475083, 30270.243243918914, 39151.60848391519, 39183.0, 39220.0, 39317.606823931754, 37963.620363796355, 38455.230895382076, 39474.605253947455, 39526.40710389346, 39596.0, 8259.587020648974, 8382.036065852413, 8420.83158336836, 8428.0, 8453.535055571941, 8187.26314631683, 8286.212809783061, 8307.584620768968, 8310.33517318616, 8334.291585215264, 8221.588920553979, 8274.627641756135, 8306.335493160532, 8309.086000539935, 8312.04411492677, 8243.8351232975, 8244.134365891556, 8250.422470427073, 8258.587070646474, 8269.25576698097, 8088.0, 8091.352691784702, 8168.387370947144, 8174.959125204396, 8193.8361232775, 37533.81233093817, 37744.24511509755, 37759.0, 37762.62237377646, 37830.054248643726, 3593.2993066352074, 3595.406757884946, 3603.4414665726854, 3628.4013137832226, 3634.436662317345, 30434.84782576095, 30440.54339184914, 30485.695143048728, 30536.69463305361, 30613.23466913345, 3001.35470873761, 3088.366884788613, 3107.254258977852, 3127.874885004597, 3142.4343618148723, 246581.53418465765, 251030.0, 253252.4674753266, 301703.49148254335, 310446.0, 39078.804605977355, 39193.80403097966, 46928.0, 47152.52847471481, 47430.5256947433, 50213.74893125511, 50297.24554131691, 50492.24261636078, 50656.49343506592, 50674.0, 14227.14637121776, 14285.571432856823, 14380.85619143816, 14409.42362305496, 14410.42358305656, 11570.247933884271, 11725.765484690259, 11737.765244695058, 11738.295702257721, 11738.647840564796, 11814.05487560993, 12195.878041219652, 12976.805347919788, 12983.870161298455, 12994.740105198032, 5279.208118782137, 5283.286756287929, 5291.814786482531, 5293.258943747878, 5310.097283461821, 883.2580632268925, 992.6773798515367, 1004.6734811186394, 1005.4520286443759, 1023.155896385485, 19585.510362240773, 22864.542709145724, 23256.53486930252, 23390.766092338858, 24312.0, 969.4086607169621, 1135.8750537440874, 1147.143612405122, 1153.798085335063, 1165.9650210493699, 13984.440622375194, 15678.216089195723, 16351.83648163527, 16666.749998750027, 16852.74720879188, 333539.0, 336575.0, 336944.0, 337395.0, 337489.0, 57523.424765751806, 57910.42089579134, 58117.41882581205, 58206.0, 58429.12356314659, 1429.6354429620587, 1512.0625212368402, 1521.1329542161084, 1526.2902750220978, 1529.7552391617287, 23251.953662084907, 27318.1804545856, 28145.0, 30056.098317050524, 30301.393972119993, 37964.81017595005, 40139.996500087735, 40142.0, 40262.1947561053, 40392.0, 228342.0, 298234.0, 298349.0, 299071.5046424754, 299635.0, 34192.65807341895, 34382.31235375329, 34802.0, 35547.46678799872, 35561.64438355583, 42847.57152428436, 43670.34494482649, 45165.32252016288, 45716.77141614271, 45724.8568785783, 26318.342041449116, 26542.734572654797, 28865.85567072151, 29213.415731685673, 30242.24394390158, 25524.872375638002, 29651.703482965757, 29775.553366699085, 29903.700962990093, 31172.844135779174, 36198.0, 37429.62570374261, 37474.625253747115, 37492.06269843276, 37528.24943501169, 27519.58720619152, 29240.853795730884, 30608.08175754731, 30799.076027719206, 32533.674663253063, 23305.0, 24061.398465038514, 24640.753592464564, 24671.876640616683, 25023.624645629967], "value": 17426.209241645643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.91676539840114, 505.11856809866765, 508.7865440923397, 524.6013030097125, 531.5906751801116, 21973.450663733416, 21998.45003874904, 22452.55094898105, 22856.085756569737, 22892.084316627337, 24081.63877541838, 24559.75440245597, 24863.62704559433, 24906.626400604004, 24935.50128997419, 6487.610743355402, 6635.867282654344, 6682.699278532477, 6688.3646053624825, 6714.328567143271, 6540.117084193621, 6673.4661227101715, 6677.265500794909, 6679.565828221174, 6685.5320127591085, 5665.3768085510565, 6304.077256436785, 6311.337309582496, 6334.3032266450655, 6369.872602547946, 5281.445448227938, 6303.590266632654, 6346.174997250351, 6351.968240158816, 6370.0, 4848.733319667417, 5406.83779486616, 5812.564057695689, 5832.591718579701, 5858.0, 29009.70990290091, 29739.405211895642, 29845.85077074601, 30268.69731302681, 30421.847890760626, 2185.530111026137, 2203.5482726041123, 2235.0165926992236, 2237.071615279657, 2237.105157936822, 19112.713309300234, 19487.415377538553, 19673.704894426453, 19694.0, 19922.203111875508, 1911.5185731058402, 2189.9671504927437, 2245.5396643688005, 2250.9212177573704, 2263.19656521934, 140147.29926350302, 165728.17135914363, 165850.0, 168142.0, 206778.0, 13602.455901763818, 13970.161790292615, 14404.063735857137, 15376.308066137051, 15450.6137346565, 20669.86315752618, 20694.965251737354, 20723.58552828935, 20742.48143796387, 20768.065437055368, 9660.130588246951, 9815.0, 9985.700428987142, 9989.80020399588, 10023.548940297704, 7505.774826755206, 9078.773030674285, 9105.726828195166, 9129.269658427309, 9169.954150229207, 7412.96293518529, 9277.907220927704, 9300.209482193954, 9311.813763724687, 9321.64836098762, 2597.9610305845426, 2602.557565213918, 2603.2580714496276, 2627.908023219178, 2739.904103356373, 815.502543448496, 930.4835816121982, 943.6178347769164, 999.9200063994863, 1001.1490233301636, 26000.34999125037, 28708.0, 29033.85483072571, 29117.0, 29292.560611590845, 858.6479543387306, 988.2538683294067, 1014.518103900656, 1032.9702135304162, 1034.5448002878709, 11051.0, 11090.0, 11361.829572556418, 13743.0, 14895.329710162852, 543787.0, 547342.8718654986, 558268.0, 560887.1955640278, 568796.0, 148788.256058719, 150399.99200016158, 153381.23309383605, 153630.0, 156413.2179339096, 1154.2785758900686, 1216.6410908782107, 1219.1709637446445, 1240.9007279417624, 1242.3663931394874, 22913.541729165656, 24152.27127319241, 24203.06375489992, 24224.0, 24273.150439734523, 69266.30733692598, 72799.0, 72887.63556182185, 73502.63248683722, 74329.0, 72812.90780638401, 88280.1171988272, 92252.61621075823, 95188.52405737927, 96259.55610665986, 11503.424828758696, 11754.23597466162, 11826.290422574673, 11913.22564033335, 12013.819792702941, 17867.821321786614, 18216.180271887533, 18370.54073648116, 18435.26258949627, 18743.0, 14617.269136542918, 14809.555713328617, 15055.397784088515, 15159.469418570297, 15164.165970871709, 11195.720106997065, 13684.52104176349, 13839.446422143, 13938.30308484592, 14308.356123974238, 15891.523254302389, 16002.759958600864, 16021.118838463997, 16035.518934431986, 16390.672186555963, 11872.228305160137, 11994.64016079519, 12050.517979281081, 12172.208806427909, 12182.14724969279, 13549.59351219465, 14601.853981460474, 15161.317740701474, 15273.0, 15506.612334691272], "value": 11883.095772430994, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.4096999745107, 1466.435422362391, 1480.2968589919797, 1498.9250537473124, 1510.6676531163148, 11148.999999999989, 13006.349682515873, 13531.729365412686, 13534.661633459169, 13578.3210839458, 9288.0, 11077.11383089353, 11394.031507321888, 11631.941840290809, 12025.879741202585, 2678.5982102684607, 2731.1533424638424, 2734.4257705881787, 2735.603337516059, 2738.6850512191054, 2656.9601455978177, 2685.3555146764734, 2693.676758788948, 2710.0514819813106, 2711.4170453352526, 2650.09896635144, 2672.839629622219, 2676.330917270682, 2684.5302072137397, 2687.072959828856, 2207.8896055197174, 2305.827062970284, 2640.234332043705, 2648.708642049373, 2660.2950218192195, 2315.617923042696, 2339.087755775249, 2609.2433194373607, 2703.2836298380944, 2712.1185614675114, 12773.48906043757, 12864.935675321563, 12882.935585322108, 12889.548865789653, 12890.677733056655, 7028.156621205437, 7247.347738703489, 7325.120985481723, 7328.890066649004, 7332.56004639718, 4471.530489298641, 4756.476787553366, 4845.055214233226, 4867.36724225852, 4869.245266983623, 1390.242317936727, 1391.4364682303683, 1408.3943904121224, 1437.7699568069058, 1447.1100273331824, 19572.412827615193, 19617.0, 19687.803121968882, 19699.507512312164, 19799.901000494905, 6383.840403989938, 6736.797896063126, 6826.556273842184, 6972.4073453757, 7062.081929349151, 16179.514614561582, 16187.433439829549, 16211.0, 16250.0, 16259.51221463358, 7151.7854464366155, 7549.50928189666, 7591.164971853092, 7595.354394876497, 7615.238476152341, 6548.9017664735065, 6619.073329733841, 6723.2519544917695, 6753.425958793459, 6807.18313802337, 5119.078565858182, 5224.50367215114, 5282.286891269706, 5317.9202311965355, 5385.892282154335, 1628.0463443867407, 1655.4619748581517, 1662.2187571841216, 1675.7653928450027, 1676.9832301676827, 819.1685439279137, 837.3552364679163, 840.3277378097498, 857.4598003257908, 872.7207293666092, 6895.392358999429, 6970.0938877946555, 7162.319579639926, 7221.89167162493, 7256.6734496947765, 687.8933765266391, 712.6508011074536, 716.9784906452815, 721.2499001038966, 723.280336065618, 3495.475678648223, 4182.9581704182565, 4262.211490874198, 4282.914341713148, 4491.317119961665, 17087.743683844754, 17908.46274611764, 18056.90971545134, 18057.006864622308, 18062.90968545149, 17318.826811731975, 17513.387031454095, 17784.822151778575, 17990.370337038403, 18090.90954545245, 9181.495017774087, 9203.907960920438, 9295.256379489625, 9344.95327523358, 9407.0, 7292.489525733148, 7350.0, 7656.961715191388, 7695.769126926201, 7893.052833659997, 14207.218602976936, 15484.225788710293, 15739.842601573837, 15889.443869464514, 16015.199240038184, 15254.389824406899, 16027.679446411239, 16268.593285167966, 16363.59091022734, 16372.754408684117, 9659.661911833053, 9984.450855203033, 10511.894881051398, 10673.786524269626, 10747.677569672922, 12053.0, 12066.638000859988, 12078.879211207775, 12079.094067944763, 12114.454849531616, 9623.470709111067, 9713.0, 9862.358946668444, 9898.653547125816, 10012.0, 9637.807243855224, 9644.614215431584, 9819.607215711292, 9846.901530984598, 9911.405315681082, 9074.364794464323, 9119.407238529475, 9188.173064423967, 9196.218321442484, 9211.585478653342, 6451.677416129269, 6496.7726129585235, 6863.656817159222, 6881.243063263138, 6972.965135174291, 6751.831204219738, 6802.97955306708, 7048.189458212371, 7072.752453664097, 7110.537815042213], "value": 5956.111956597022, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.4095618524529, 447.621759613127, 455.71062375391625, 469.40151307083465, 470.52712024415456, 5887.2346594942655, 5908.852278693035, 6471.352864713532, 6506.674666266691, 6524.836879078013, 5523.177351474014, 6178.598391104586, 6368.267649220341, 6427.839304017402, 6498.447631951278, 4762.952370476303, 4764.833230836922, 4779.426468823746, 4796.520347965193, 4806.206975848998, 4778.542544523919, 4780.426348838127, 4797.9760101199445, 4811.975940120312, 4817.22924332107, 4740.028294199681, 4753.786079626408, 4757.78676437508, 4772.756804301973, 4773.474917759044, 3757.1170774867883, 3892.9026774330587, 4040.9595904040875, 4554.6128579070655, 4776.379070720802, 4162.023039056454, 4164.12553363794, 4168.583141685837, 4175.352820312856, 4184.121334519755, 6018.247719035121, 6218.28489723683, 6239.032949892774, 6284.591501552384, 6287.6856157192415, 5611.018071837412, 5645.01212287848, 5658.886822263573, 5661.46216109469, 5667.744951477193, 6243.940469170119, 6826.863462730717, 6834.352640204659, 6845.691943862538, 6859.2454829968665, 4351.26028575143, 4608.916904527467, 4731.817045738565, 4742.004179122388, 4761.571458568746, 6942.548734332303, 7211.495195336329, 7267.418606511466, 7320.890186647204, 7347.228541003171, 3986.481757371523, 4737.620990320766, 4862.319275301461, 4871.658983871095, 4880.731559764176, 4658.930116048262, 4679.087577922341, 4951.480094590088, 4959.256111583291, 4992.650514464025, 5620.241267428926, 5673.149027645886, 5694.259746232963, 5699.5725320600295, 5710.2862142232225, 6345.49236061114, 6918.965405172942, 6926.714184319163, 6938.687759050855, 6966.582005079711, 1528.9388424462898, 1616.822149563547, 1680.0507713142035, 1681.8318168183084, 1686.6169740812416, 1085.4843949124104, 1127.3517727306833, 1133.880942501042, 1136.8351589019546, 1140.1790710688292, 5424.349078110652, 5545.61180717354, 6184.196054512884, 6271.982414068818, 6332.905006424908, 5590.217369568264, 6423.935760642427, 7038.436925045984, 7110.466714996396, 7436.070491188601, 5683.60214784961, 6380.7447702091395, 6411.967940160363, 6434.774782882577, 6437.388448097423, 3344.39800835847, 3527.4073555936366, 3540.5574303212097, 3631.673149416565, 3633.5662798655544, 6688.297728738413, 7166.498345115792, 7224.422046236288, 7274.818129546804, 7284.635768211569, 6479.7408103675325, 6525.955847064447, 7167.3549380556, 7250.6374681265725, 7301.963490182515, 6084.847878802977, 6241.34465881085, 6671.833204169838, 6989.741846467685, 7310.305520975498, 5650.672092058281, 7048.471364647569, 7058.364747172779, 7097.893531597134, 7250.093738282715, 5813.738381772746, 6784.812657784966, 6911.0, 6965.0, 6971.128608923885, 5923.548730560868, 5998.34018258, 6885.449164066963, 7247.4926755128745, 7372.189059203591, 4716.976415117902, 5092.111572890067, 5304.151335786256, 5406.972965135149, 5416.918746218731, 6963.512554121163, 7042.823929401601, 7081.68132434052, 7084.964575177091, 7087.3267039631155, 5693.715314234189, 6782.660866956534, 6803.040677305388, 6816.2842901496615, 6846.021980438525, 5556.499915007668, 6472.902906456313, 6796.830079248059, 6825.453963682993, 6848.8972665409065, 5318.97340513295, 6637.1703537057865, 6656.301088385843, 6672.866542669022, 6685.699143538455, 6155.753769849156, 6592.604443733391, 6766.699322230465, 6771.221309549268, 6772.762953296611, 6263.311035785969, 6394.744210231539, 6499.967500162469, 6549.574277671936, 6609.959418667467], "value": 5017.303768729757, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.5162924801247, 830.236182711905, 838.2832678060262, 859.2395729779146, 874.147705986663, 17066.0, 17117.97292162468, 17256.74114888278, 17356.47930562085, 17560.736588951175, 9775.755606109851, 13225.20648761075, 13466.461341546326, 16175.757363639555, 16867.24097415616, 958.2094771813247, 960.6349587156906, 974.8830140383164, 981.2885657898003, 981.3915372469079, 981.7349315684761, 1011.0294117647068, 1011.8279892418307, 1012.0385633648027, 1013.5337744637476, 1054.1408751867205, 1065.9413732244723, 1068.6206396729165, 1069.7004838645153, 1071.2982996137541, 933.7385532051037, 995.1740055753708, 1060.2737125069275, 1066.1577353890418, 1074.5862842805498, 642.7075680565332, 770.4876257288896, 774.411447300056, 774.7133560582564, 778.1051790441038, 16830.410935617194, 17262.913685431493, 17336.479905602853, 17457.03986280754, 17458.825411745845, 15697.450589229436, 17505.7374139388, 17685.823141768677, 17962.640747185113, 18175.272989080422, 14403.711925761427, 14613.92693036528, 14728.116313021254, 14799.0, 14855.628609284746, 3248.31785325082, 3537.7523573349877, 3579.552555930509, 3602.9279414411826, 3606.296772129436, 147070.26464867714, 152236.47763522333, 155846.6623000656, 158710.20644896818, 158740.0, 12879.678008049874, 13512.324383780771, 13615.18308901469, 13631.182129072287, 13710.794338084937, 26090.347741306618, 26579.33551661224, 26751.732482674925, 27001.0, 27025.0, 7879.172686867911, 8006.239407256301, 8020.35837133028, 8029.759107226676, 8063.314618257514, 3761.191343861028, 3781.9243615128094, 3820.465134881119, 3833.8274777635074, 3836.7889766063136, 4592.219322715079, 4618.630509559227, 4644.326572646947, 4673.579377856009, 4681.461631912373, 926.4348747264163, 969.7381706939087, 981.4405788700373, 983.4836710726926, 986.7681094942683, 644.432899048834, 649.3052433895687, 660.1286302081206, 673.6362364323211, 681.0057316318231, 10957.17821163416, 11885.038968570261, 12389.318587477595, 12575.245485270912, 12601.306928119042, 626.37988391493, 646.747768370336, 651.739304278283, 670.1656437734954, 677.3734295776387, 5230.895382092337, 5540.529055030368, 5587.608867379245, 5751.4823665869435, 5826.0, 124873.0, 135181.3240933789, 137446.0, 145626.5437345613, 146400.0, 100432.0, 102840.0, 106638.46680766546, 106653.46673266584, 107930.0, 96661.51669241609, 102018.0, 102382.0, 103027.4848625752, 106371.0, 10991.560337586407, 11476.902410507657, 11715.414229288672, 12076.275423474619, 12931.5473958411, 44440.33339499845, 45546.31680524861, 48288.55134345975, 49352.76618084576, 49565.0, 62749.68625156845, 62824.05763913454, 66034.0, 66257.0, 67578.66210668915, 16584.585385365463, 16851.578710532336, 17133.143342833057, 17334.82665173332, 17425.912870435568, 15661.530154095395, 17876.731849022533, 18367.989760563298, 18370.908145459187, 18424.35514756977, 16874.578135546708, 16901.661966760843, 17101.57246068858, 17440.82559174392, 17581.648367032332, 16425.425110121087, 16740.24668889927, 17034.48896533106, 17361.30554777845, 17466.563335916195, 16344.264508096927, 16533.256003479633, 16540.338386464406, 16803.907745996472, 16810.915945420686, 11136.38749868765, 11476.770464590494, 11565.710857228636, 12096.274223546616, 12205.20666156697, 10829.278798617805, 12351.69120771988, 12627.684307892376, 13047.151935124186, 13260.071794974645], "value": 8692.742024671497, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 117.0, 126.0, 118.0, 115.0, 118.0, 114.0, 115.0, 119.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 118.0, 149.0, 166.0, 138.0, 173.0, 134.0, 110.0, 156.0, 136.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 199.0, 198.0, 213.0, 220.0, 237.0, 270.0, 251.0, 199.0, 196.0], "value": 218.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 86.0, 70.0, 130.0, 76.0, 63.0, 73.0, 63.0, 103.0, 61.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 112.0, 69.0, 73.0, 79.0, 111.0, 70.0, 78.0, 128.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 90.0, 90.0, 92.0, 91.0, 90.0, 90.0, 90.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 124.0, 94.0, 152.0, 122.0, 98.0, 97.0, 108.0, 98.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 115.0, 83.0, 148.0, 127.0, 139.0, 112.0, 92.0, 92.0], "value": 119.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 53.0, 51.0, 54.0, 53.0, 51.0, 66.0, 81.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 65.0, 85.0, 87.0, 63.0, 74.0, 91.0, 85.0, 58.0, 56.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 79.0, 81.0, 82.0, 80.0, 84.0, 83.0, 82.0, 83.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 154.0, 152.0, 140.0, 141.0, 140.0, 161.0, 159.0, 150.0, 194.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 179.0, 181.0, 235.0, 175.0, 181.0, 176.0, 174.0, 187.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 67.0, 70.0, 79.0, 95.0, 98.0, 85.0, 65.0, 66.0], "value": 75.7, "unit": "score"}]}]} 20:38:18 INFO - Return code: 0 20:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:38:18 INFO - # TBPL SUCCESS # 20:38:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:38:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:38:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:38:18 INFO - 2017-06-13 20:38:18.723 screenresolution[1670:12491] starting screenresolution argv=screenresolution get 20:38:18 INFO - 2017-06-13 20:38:18.726 screenresolution[1670:12491] Display 0: 1600x1200x32@60 20:38:18 INFO - 2017-06-13 20:38:18.732 screenresolution[1671:12494] starting screenresolution argv=screenresolution list 20:38:18 INFO - Available Modes on Display 0 20:38:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:38:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:38:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:38:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:38:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:38:18 INFO - Intel Iris: 20:38:18 INFO - Chipset Model: Intel Iris 20:38:18 INFO - Type: GPU 20:38:18 INFO - Bus: Built-In 20:38:18 INFO - VRAM (Dynamic, Max): 1536 MB 20:38:18 INFO - Vendor: Intel (0x8086) 20:38:18 INFO - Device ID: 0x0a2e 20:38:18 INFO - Revision ID: 0x0009 20:38:18 INFO - Displays: 20:38:18 INFO - Display: 20:38:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:38:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:38:18 INFO - Display Serial Number: 1600x1200 60 20:38:18 INFO - Main Display: Yes 20:38:18 INFO - Mirror: Off 20:38:18 INFO - Online: Yes 20:38:18 INFO - Rotation: Supported 20:38:18 INFO - Return code: 0 20:38:18 INFO - Running post-action listener: _resource_record_post_action 20:38:18 INFO - [mozharness: 2017-06-14 03:38:18.968411Z] Finished run-tests step (success) 20:38:18 INFO - Running post-run listener: _resource_record_post_run 20:38:19 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 34681856; Write bytes: 575036928; Read time: 216; Write time: 1823 20:38:19 INFO - TinderboxPrint: CPU usage
53.5% 20:38:19 INFO - TinderboxPrint: I/O read bytes / time
34,681,856 / 216 20:38:19 INFO - TinderboxPrint: I/O write bytes / time
575,036,928 / 1,823 20:38:19 INFO - TinderboxPrint: CPU idle
2,341.8 (46.5%) 20:38:19 INFO - TinderboxPrint: CPU system
1,023.6 (20.3%) 20:38:19 INFO - TinderboxPrint: CPU user
1,668.3 (33.1%) 20:38:19 INFO - TinderboxPrint: Swap in / out
298,373,120 / 0 20:38:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 246604288; Write bytes: 241981440; Read time: 17756; Write time: 511 20:38:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 20:38:19 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 32637952; Write bytes: 316802560; Read time: 197; Write time: 1264 20:38:19 INFO - Running post-run listener: _upload_blobber_files 20:38:19 INFO - Blob upload gear active. 20:38:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:38:19 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 20:38:19 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'] 20:38:19 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 20:38:19 INFO - (blobuploader) - INFO - Open directory for files ... 20:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:38:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:21 INFO - (blobuploader) - INFO - Done attempting. 20:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:38:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:22 INFO - (blobuploader) - INFO - Done attempting. 20:38:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:38:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:38:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:23 INFO - (blobuploader) - INFO - Done attempting. 20:38:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:38:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:38:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:38:25 INFO - (blobuploader) - INFO - Done attempting. 20:38:25 INFO - (blobuploader) - INFO - Iteration through files over. 20:38:25 INFO - Return code: 0 20:38:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:38:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:38:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117a7ecfd9cad1cea5175c29f6d0f7b48b9a1d20a39ea2d1df48f63e791c1766410fac98375886796c70820425fa906bba8746786b3e49b5628140a894cb74c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fa005ad0264b0373a5e1e911f3d8966170b4f7fa02be3cbf1fe23c4f7a19118d4d88454522f588400f4f5343dd7c7ddccdd2b0f7f14f16b2fd467fdcc3f15c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d3ebc72f8afe32088c7046c78ea323c423b385db17bec9f037c0e873889a28d618dad9fef97dd8a47b4a4b5cb22c6470ac40196d5ed91d527d6a887390aed23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/945c097aa73347f57d47714280c412f888db4f1c723005d7679b10799c0a4cf45d9bd33a34768278e02fdf1d2c2118577e142f7f0e94dec25c9ced644d255b21"} 20:38:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:38:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:38:25 INFO - Contents: 20:38:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117a7ecfd9cad1cea5175c29f6d0f7b48b9a1d20a39ea2d1df48f63e791c1766410fac98375886796c70820425fa906bba8746786b3e49b5628140a894cb74c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fa005ad0264b0373a5e1e911f3d8966170b4f7fa02be3cbf1fe23c4f7a19118d4d88454522f588400f4f5343dd7c7ddccdd2b0f7f14f16b2fd467fdcc3f15c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d3ebc72f8afe32088c7046c78ea323c423b385db17bec9f037c0e873889a28d618dad9fef97dd8a47b4a4b5cb22c6470ac40196d5ed91d527d6a887390aed23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/945c097aa73347f57d47714280c412f888db4f1c723005d7679b10799c0a4cf45d9bd33a34768278e02fdf1d2c2118577e142f7f0e94dec25c9ced644d255b21"} 20:38:25 INFO - Running post-run listener: copy_logs_to_upload_dir 20:38:25 INFO - Copying logs to upload dir... 20:38:25 INFO - mkdir: /builds/slave/test/build/upload/logs 20:38:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.942370 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-13 20:38:25.216344) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 20:38:25.220208) ========= 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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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/117a7ecfd9cad1cea5175c29f6d0f7b48b9a1d20a39ea2d1df48f63e791c1766410fac98375886796c70820425fa906bba8746786b3e49b5628140a894cb74c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fa005ad0264b0373a5e1e911f3d8966170b4f7fa02be3cbf1fe23c4f7a19118d4d88454522f588400f4f5343dd7c7ddccdd2b0f7f14f16b2fd467fdcc3f15c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d3ebc72f8afe32088c7046c78ea323c423b385db17bec9f037c0e873889a28d618dad9fef97dd8a47b4a4b5cb22c6470ac40196d5ed91d527d6a887390aed23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/945c097aa73347f57d47714280c412f888db4f1c723005d7679b10799c0a4cf45d9bd33a34768278e02fdf1d2c2118577e142f7f0e94dec25c9ced644d255b21"} build_url:https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010981 build_url: 'https://queue.taskcluster.net/v1/task/JydRJCnVQrij1JAs5zBJTQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117a7ecfd9cad1cea5175c29f6d0f7b48b9a1d20a39ea2d1df48f63e791c1766410fac98375886796c70820425fa906bba8746786b3e49b5628140a894cb74c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fa005ad0264b0373a5e1e911f3d8966170b4f7fa02be3cbf1fe23c4f7a19118d4d88454522f588400f4f5343dd7c7ddccdd2b0f7f14f16b2fd467fdcc3f15c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d3ebc72f8afe32088c7046c78ea323c423b385db17bec9f037c0e873889a28d618dad9fef97dd8a47b4a4b5cb22c6470ac40196d5ed91d527d6a887390aed23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/945c097aa73347f57d47714280c412f888db4f1c723005d7679b10799c0a4cf45d9bd33a34768278e02fdf1d2c2118577e142f7f0e94dec25c9ced644d255b21"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 20:38:25.257348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 20:38:25.258128) ========= 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.SQQ7INSuVU/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.ZqhyQkhrpm/Listeners TMPDIR=/var/folders/2h/m728c6kn5w33qr6tk69wj79m00000w/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.005265 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 20:38:25.312053) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 20:38:25.312506) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 20:38:25.312878) ========= ========= Total master_lag: 0.19 =========