builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0363 starttime: 1497199496.46 results: success (0) revision: 981da978f1f686ad024fa958c9d27d2f8acc5ad0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:56.456944) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:56.457332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:56.457607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:56.675875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:56.676247) ========= 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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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-11 09:44:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-06-11 09:44:57 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.661503 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:57.364015) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:57.364361) ========= 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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.026935 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:57.442064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:57.442416) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 981da978f1f686ad024fa958c9d27d2f8acc5ad0 --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 981da978f1f686ad024fa958c9d27d2f8acc5ad0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TChSH8hQ1I/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.lwOuXHEaxF/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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-11 09:44:57,548 truncating revision to first 12 chars 2017-06-11 09:44:57,548 Setting DEBUG logging. 2017-06-11 09:44:57,548 attempt 1/10 2017-06-11 09:44:57,548 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/981da978f1f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-11 09:44:57,838 unpacking tar archive at: mozilla-central-981da978f1f6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.571730 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:58.086155) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:58.086470) ========= script_repo_revision: 981da978f1f686ad024fa958c9d27d2f8acc5ad0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:58.086828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:58.087089) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-11 09:44:58.144069) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-11 09:44:58.144366) ========= /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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:44:58 INFO - MultiFileLogger online at 20170611 09:44:58 in /builds/slave/test 09:44:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:44:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:44:58 INFO - {'append_to_log': False, 09:44:58 INFO - 'base_work_dir': '/builds/slave/test', 09:44:58 INFO - 'blob_upload_branch': 'Firefox', 09:44:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:44:58 INFO - 'branch': 'Firefox', 09:44:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:44:58 INFO - 'config_files': ('talos/mac_config.py',), 09:44:58 INFO - 'default_actions': ('clobber', 09:44:58 INFO - 'read-buildbot-config', 09:44:58 INFO - 'download-and-extract', 09:44:58 INFO - 'populate-webroot', 09:44:58 INFO - 'create-virtualenv', 09:44:58 INFO - 'install', 09:44:58 INFO - 'setup-mitmproxy', 09:44:58 INFO - 'run-tests'), 09:44:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:44:58 INFO - 'download_minidump_stackwalk': True, 09:44:58 INFO - 'download_symbols': 'ondemand', 09:44:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:44:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:44:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:44:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:44:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:44:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:44:58 INFO - 'gecko_profile': False, 09:44:58 INFO - 'gecko_profile_interval': 0, 09:44:58 INFO - 'installer_path': 'installer.exe', 09:44:58 INFO - 'log_level': 'info', 09:44:58 INFO - 'log_name': 'talos', 09:44:58 INFO - 'log_to_console': True, 09:44:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:44:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:44:58 INFO - 'opt_config_files': (), 09:44:58 INFO - 'pip_index': False, 09:44:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:44:58 INFO - 'cmd': ('bash', 09:44:58 INFO - '-c', 09:44:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:44:58 INFO - 'enabled': True, 09:44:58 INFO - 'halt_on_failure': False, 09:44:58 INFO - 'name': 'check_screen_resolution'},), 09:44:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:44:58 INFO - 'cmd': ('bash', 09:44:58 INFO - '-c', 09:44:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:44:58 INFO - 'enabled': True, 09:44:58 INFO - 'halt_on_failure': False, 09:44:58 INFO - 'name': 'check_screen_resolution'},), 09:44:58 INFO - 'run_cmd_checks_enabled': True, 09:44:58 INFO - 'suite': 'dromaeojs-e10s', 09:44:58 INFO - 'system_bits': '32', 09:44:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:44:58 INFO - 'title': 't-yosemite-r7-0363', 09:44:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:44:58 INFO - 'use_talos_json': True, 09:44:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:44:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:44:58 INFO - 'work_dir': 'build'} 09:44:58 INFO - [mozharness: 2017-06-11 16:44:58.356503Z] Running clobber step. 09:44:58 INFO - Running pre-action listener: _resource_record_pre_action 09:44:58 INFO - Running main action method: clobber 09:44:58 INFO - rmtree: /builds/slave/test/build 09:44:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:45:00 INFO - Running post-action listener: _resource_record_post_action 09:45:00 INFO - [mozharness: 2017-06-11 16:45:00.909122Z] Finished clobber step (success) 09:45:00 INFO - [mozharness: 2017-06-11 16:45:00.909406Z] Running read-buildbot-config step. 09:45:00 INFO - Running pre-action listener: _resource_record_pre_action 09:45:00 INFO - Running main action method: read_buildbot_config 09:45:00 INFO - Using buildbot properties: 09:45:00 INFO - { 09:45:00 INFO - "project": "", 09:45:00 INFO - "product": "firefox", 09:45:00 INFO - "who": "ffxbld@noreply.mozilla.org", 09:45:00 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:45:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:45:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:45:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:45:00 INFO - "stage_platform": "macosx64", 09:45:00 INFO - "basedir": "/builds/slave/test", 09:45:00 INFO - "buildnumber": 23, 09:45:00 INFO - "platform": "yosemite_r7", 09:45:00 INFO - "parent_task_id": "VoiAB_HTS2mKcMfz8zjcVg", 09:45:00 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:45:00 INFO - "slavebuilddir": "test", 09:45:00 INFO - "taskId": "Vsqjn8KUQcmX77PHO0YzgA", 09:45:00 INFO - "branch": "mozilla-central", 09:45:00 INFO - "script_repo_revision": "production", 09:45:00 INFO - "revision": "981da978f1f686ad024fa958c9d27d2f8acc5ad0", 09:45:00 INFO - "slavename": "t-yosemite-r7-0363", 09:45:00 INFO - "repo_path": "mozilla-central" 09:45:00 INFO - } 09:45:00 INFO - Finding installer, test and symbols from parent task. 09:45:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vsqjn8KUQcmX77PHO0YzgA'}, attempt #1 09:45:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vsqjn8KUQcmX77PHO0YzgA'}, attempt #1 09:45:02 INFO - Task dependencies: ejDSg0mWQ8aTsqm1iBcXqg VoiAB_HTS2mKcMfz8zjcVg 09:45:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg'}, attempt #1 09:45:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:45:02 INFO - Set test_url: https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/test_packages.json 09:45:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/target.crashreporter-symbols.zip 09:45:02 INFO - Running post-action listener: _resource_record_post_action 09:45:02 INFO - [mozharness: 2017-06-11 16:45:02.632618Z] Finished read-buildbot-config step (success) 09:45:02 INFO - [mozharness: 2017-06-11 16:45:02.632731Z] Running download-and-extract step. 09:45:02 INFO - Running pre-action listener: _resource_record_pre_action 09:45:02 INFO - Running main action method: download_and_extract 09:45:02 INFO - mkdir: /builds/slave/test/build/tests 09:45: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')]} 09:45:02 INFO - trying https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:45:02 INFO - Downloading https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:45:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:45:03 INFO - Downloaded 1328 bytes. 09:45:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:45:03 INFO - Using the following test package requirements: 09:45:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:45:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:45:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:45:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:45:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'jsshell-mac.zip'], 09:45:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:45:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:45:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:45:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:45:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:45:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:45:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:45:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:45:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:45:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:45:03 INFO - Fetch https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:45:05 INFO - Content-Length response header: 59540569 09:45:05 INFO - Bytes received: 59540569 09:45:23 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:45:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:45:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:45:23 INFO - Fetch https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:45:25 INFO - Content-Length response header: 59540569 09:45:25 INFO - Bytes received: 59540569 09:45:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:45:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:45:42 INFO - Fetch https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:45:43 INFO - Content-Length response header: 14450010 09:45:43 INFO - Bytes received: 14450010 09:45:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:43 INFO - trying https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:45:43 INFO - Downloading https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:45:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:45:45 INFO - Downloaded 69315598 bytes. 09:45:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:45:45 INFO - mkdir: /builds/slave/test/properties 09:45:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:45:45 INFO - Writing to file /builds/slave/test/properties/build_url 09:45:45 INFO - Contents: 09:45:45 INFO - build_url:https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:45:45 INFO - Running post-action listener: _resource_record_post_action 09:45:45 INFO - Running post-action listener: set_extra_try_arguments 09:45:45 INFO - [mozharness: 2017-06-11 16:45:45.728108Z] Finished download-and-extract step (success) 09:45:45 INFO - [mozharness: 2017-06-11 16:45:45.728262Z] Running populate-webroot step. 09:45:45 INFO - Running pre-action listener: _resource_record_pre_action 09:45:45 INFO - Running main action method: populate_webroot 09:45:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:45:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:45:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:45:45 INFO - '--tppagecycles', 09:45:45 INFO - '7'], 09:45:45 INFO - 'tests': ['tsvgm']}}, 09:45:45 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:45:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:45:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:45:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['damp', 'tps']}, 09:45:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'tests': ['damp', 'tps']}, 09:45:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['dromaeo_dom']}, 09:45:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:45:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:45:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:45:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:45:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['a11yr', 09:45:45 INFO - 'ts_paint', 09:45:45 INFO - 'tpaint', 09:45:45 INFO - 'sessionrestore', 09:45:45 INFO - 'sessionrestore_no_auto_restore', 09:45:45 INFO - 'tabpaint']}, 09:45:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:45:45 INFO - 'ts_paint', 09:45:45 INFO - 'tpaint', 09:45:45 INFO - 'sessionrestore', 09:45:45 INFO - 'sessionrestore_no_auto_restore', 09:45:45 INFO - 'tabpaint']}, 09:45:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:45:45 INFO - 'ts_paint', 09:45:45 INFO - 'tpaint', 09:45:45 INFO - 'sessionrestore', 09:45:45 INFO - 'sessionrestore_no_auto_restore', 09:45:45 INFO - 'tabpaint']}, 09:45:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:45:45 INFO - 'ts_paint', 09:45:45 INFO - 'tpaint', 09:45:45 INFO - 'sessionrestore', 09:45:45 INFO - 'sessionrestore_no_auto_restore', 09:45:45 INFO - 'tabpaint']}, 09:45:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['a11yr', 09:45:45 INFO - 'ts_paint', 09:45:45 INFO - 'tpaint', 09:45:45 INFO - 'sessionrestore', 09:45:45 INFO - 'sessionrestore_no_auto_restore', 09:45:45 INFO - 'tabpaint']}, 09:45:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['a11yr', 09:45:45 INFO - 'ts_paint', 09:45:45 INFO - 'tpaint', 09:45:45 INFO - 'sessionrestore', 09:45:45 INFO - 'sessionrestore_no_auto_restore', 09:45:45 INFO - 'tabpaint']}, 09:45:45 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:45:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:45:45 INFO - 'bloom_basic_ref']}, 09:45:45 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:45:45 INFO - 'talos_options': ['--mitmproxy', 09:45:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:45:45 INFO - '--firstNonBlankPaint'], 09:45:45 INFO - 'tests': ['quantum_pageload_google', 09:45:45 INFO - 'quantum_pageload_youtube', 09:45:45 INFO - 'quantum_pageload_amazon', 09:45:45 INFO - 'quantum_pageload_facebook']}, 09:45:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['tsvgx', 09:45:45 INFO - 'tsvgr_opacity', 09:45:45 INFO - 'tart', 09:45:45 INFO - 'tscrollx', 09:45:45 INFO - 'cart', 09:45:45 INFO - 'tsvg_static']}, 09:45:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:45:45 INFO - 'tsvgr_opacity', 09:45:45 INFO - 'tart', 09:45:45 INFO - 'tscrollx', 09:45:45 INFO - 'cart', 09:45:45 INFO - 'tsvg_static']}, 09:45:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'talos_options': ['--disable-e10s'], 09:45:45 INFO - 'tests': ['tp5o']}, 09:45:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:45:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'talos_options': ['--disable-e10s', 09:45:45 INFO - '--xperf_path', 09:45:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:45 INFO - 'tests': ['tp5n']}, 09:45:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:45:45 INFO - 'talos_options': ['--xperf_path', 09:45:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:45 INFO - 'tests': ['tp5n']}}, 09:45:45 INFO - 'talos.zip': {'path': '', 09:45:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:45:45 INFO - Running post-action listener: _resource_record_post_action 09:45:45 INFO - [mozharness: 2017-06-11 16:45:45.750903Z] Finished populate-webroot step (success) 09:45:45 INFO - [mozharness: 2017-06-11 16:45:45.751050Z] Running create-virtualenv step. 09:45:45 INFO - Running pre-action listener: _resource_record_pre_action 09:45:45 INFO - Running main action method: create_virtualenv 09:45:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:45:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:45:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:45:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:45:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:45:45 INFO - Using real prefix '/tools/python27' 09:45:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:45:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:45:49 INFO - Installing pip.................done. 09:45:49 INFO - Return code: 0 09:45:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:45:49 INFO - Reading from file tmpfile_stdout 09:45:49 INFO - Output received: 09:45:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:45:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:49 INFO - 'HOME': '/Users/cltbld', 09:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:49 INFO - 'LOGNAME': 'cltbld', 09:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:49 INFO - 'MOZ_NO_REMOTE': '1', 09:45:49 INFO - 'NO_EM_RESTART': '1', 09:45:49 INFO - 'PAGER': '/bin/cat', 09:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:49 INFO - 'PWD': '/builds/slave/test', 09:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:49 INFO - 'SHELL': '/bin/bash', 09:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:45:49 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:49 INFO - 'USER': 'cltbld', 09:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:49 INFO - 'XPC_FLAGS': '0x0', 09:45:49 INFO - 'XPC_SERVICE_NAME': '0', 09:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:45:50 INFO - Cleaning up... 09:45:50 INFO - Return code: 0 09:45:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:50 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:45:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:45:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:45:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:50 INFO - 'HOME': '/Users/cltbld', 09:45:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:50 INFO - 'LOGNAME': 'cltbld', 09:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:50 INFO - 'MOZ_NO_REMOTE': '1', 09:45:50 INFO - 'NO_EM_RESTART': '1', 09:45:50 INFO - 'PAGER': '/bin/cat', 09:45:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:50 INFO - 'PWD': '/builds/slave/test', 09:45:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:50 INFO - 'SHELL': '/bin/bash', 09:45:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:45:50 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:45:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:50 INFO - 'USER': 'cltbld', 09:45:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:50 INFO - 'XPC_FLAGS': '0x0', 09:45:50 INFO - 'XPC_SERVICE_NAME': '0', 09:45:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:50 INFO - Downloading/unpacking psutil>=3.1.1 09:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:45:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:52 INFO - Installing collected packages: psutil 09:45:52 INFO - Running setup.py install for psutil 09:45:52 INFO - building 'psutil._psutil_osx' extension 09:45:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:45:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:45:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:45:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:45:53 INFO - building 'psutil._psutil_posix' extension 09:45:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:45:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:45:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:45:53 INFO - ^ 09:45:53 INFO - 1 warning generated. 09:45:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:45:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:53 INFO - Successfully installed psutil 09:45:53 INFO - Cleaning up... 09:45:53 INFO - Return code: 0 09:45:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:45:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:45:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:53 INFO - 'HOME': '/Users/cltbld', 09:45:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:53 INFO - 'LOGNAME': 'cltbld', 09:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:53 INFO - 'MOZ_NO_REMOTE': '1', 09:45:53 INFO - 'NO_EM_RESTART': '1', 09:45:53 INFO - 'PAGER': '/bin/cat', 09:45:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:53 INFO - 'PWD': '/builds/slave/test', 09:45:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:53 INFO - 'SHELL': '/bin/bash', 09:45:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:45:53 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:45:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:53 INFO - 'USER': 'cltbld', 09:45:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:53 INFO - 'XPC_FLAGS': '0x0', 09:45:53 INFO - 'XPC_SERVICE_NAME': '0', 09:45:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:45:55 INFO - Installing collected packages: mozsystemmonitor 09:45:55 INFO - Running setup.py install for mozsystemmonitor 09:45:55 INFO - Successfully installed mozsystemmonitor 09:45:55 INFO - Cleaning up... 09:45:55 INFO - Return code: 0 09:45:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:55 INFO - 'HOME': '/Users/cltbld', 09:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:55 INFO - 'LOGNAME': 'cltbld', 09:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:55 INFO - 'MOZ_NO_REMOTE': '1', 09:45:55 INFO - 'NO_EM_RESTART': '1', 09:45:55 INFO - 'PAGER': '/bin/cat', 09:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:55 INFO - 'PWD': '/builds/slave/test', 09:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:55 INFO - 'SHELL': '/bin/bash', 09:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:45:55 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:55 INFO - 'USER': 'cltbld', 09:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:55 INFO - 'XPC_FLAGS': '0x0', 09:45:55 INFO - 'XPC_SERVICE_NAME': '0', 09:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:45:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:57 INFO - Downloading functools32-3.2.3-2.tar.gz 09:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:45:57 INFO - warning: no files found matching '*.txt' 09:45:57 INFO - no previously-included directories found matching 'build' 09:45:57 INFO - no previously-included directories found matching 'dist' 09:45:57 INFO - no previously-included directories found matching '.git*' 09:45:57 INFO - Installing collected packages: jsonschema, functools32 09:45:57 INFO - Running setup.py install for functools32 09:45:57 INFO - warning: no files found matching '*.txt' 09:45:57 INFO - no previously-included directories found matching 'build' 09:45:57 INFO - no previously-included directories found matching 'dist' 09:45:57 INFO - no previously-included directories found matching '.git*' 09:45:57 INFO - Successfully installed jsonschema functools32 09:45:57 INFO - Cleaning up... 09:45:58 INFO - Return code: 0 09:45:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:58 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:45:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:45:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:58 INFO - 'HOME': '/Users/cltbld', 09:45:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:58 INFO - 'LOGNAME': 'cltbld', 09:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:58 INFO - 'MOZ_NO_REMOTE': '1', 09:45:58 INFO - 'NO_EM_RESTART': '1', 09:45:58 INFO - 'PAGER': '/bin/cat', 09:45:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:58 INFO - 'PWD': '/builds/slave/test', 09:45:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:58 INFO - 'SHELL': '/bin/bash', 09:45:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:45:58 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:45:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:58 INFO - 'USER': 'cltbld', 09:45:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:58 INFO - 'XPC_FLAGS': '0x0', 09:45:58 INFO - 'XPC_SERVICE_NAME': '0', 09:45:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:45:58 INFO - Cleaning up... 09:45:58 INFO - Return code: 0 09:45:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:58 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:45:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:45:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:58 INFO - 'HOME': '/Users/cltbld', 09:45:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:58 INFO - 'LOGNAME': 'cltbld', 09:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:58 INFO - 'MOZ_NO_REMOTE': '1', 09:45:58 INFO - 'NO_EM_RESTART': '1', 09:45:58 INFO - 'PAGER': '/bin/cat', 09:45:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:58 INFO - 'PWD': '/builds/slave/test', 09:45:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:58 INFO - 'SHELL': '/bin/bash', 09:45:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:45:58 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:45:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:58 INFO - 'USER': 'cltbld', 09:45:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:58 INFO - 'XPC_FLAGS': '0x0', 09:45:58 INFO - 'XPC_SERVICE_NAME': '0', 09:45:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:58 INFO - Downloading/unpacking blobuploader==1.2.4 09:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:59 INFO - Downloading blobuploader-1.2.4.tar.gz 09:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:46:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:46: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 09:46: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 09:46: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 09:46: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 09:46:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:46:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:46: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 09:46: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 09:46: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 09:46: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 09:46:00 INFO - Downloading docopt-0.6.1.tar.gz 09:46:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:46:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:46:00 INFO - Running setup.py install for blobuploader 09:46:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:46:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:46:01 INFO - Running setup.py install for requests 09:46:01 INFO - Running setup.py install for docopt 09:46:01 INFO - Successfully installed blobuploader requests docopt 09:46:01 INFO - Cleaning up... 09:46:01 INFO - Return code: 0 09:46:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:01 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:46:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:46:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:46:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:46:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:01 INFO - 'HOME': '/Users/cltbld', 09:46:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:01 INFO - 'LOGNAME': 'cltbld', 09:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:01 INFO - 'MOZ_NO_REMOTE': '1', 09:46:01 INFO - 'NO_EM_RESTART': '1', 09:46:01 INFO - 'PAGER': '/bin/cat', 09:46:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:01 INFO - 'PWD': '/builds/slave/test', 09:46:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:01 INFO - 'SHELL': '/bin/bash', 09:46:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:46:01 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:46:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:01 INFO - 'USER': 'cltbld', 09:46:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:01 INFO - 'XPC_FLAGS': '0x0', 09:46:01 INFO - 'XPC_SERVICE_NAME': '0', 09:46:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Fj3EPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-_5LNY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-eEJmhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-GYee4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-fPS44t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ZER1Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-c7srQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-CCNG5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-piCAeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:46:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-1i00Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-jyH87t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-ecvxPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-zvcSIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-mReSt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-l1bhE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-gW3di3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:46:03 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-C9XhVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:46:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:46:03 INFO - Running setup.py install for manifestparser 09:46:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:46:03 INFO - Running setup.py install for mozcrash 09:46:04 INFO - Running setup.py install for mozdebug 09:46:04 INFO - Running setup.py install for mozdevice 09:46:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Running setup.py install for mozfile 09:46:04 INFO - Running setup.py install for mozhttpd 09:46:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Running setup.py install for mozinfo 09:46:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Running setup.py install for mozInstall 09:46:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:46:04 INFO - Running setup.py install for mozleak 09:46:05 INFO - Running setup.py install for mozlog 09:46:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:46:05 INFO - Running setup.py install for moznetwork 09:46:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:46:05 INFO - Running setup.py install for mozprocess 09:46:05 INFO - Running setup.py install for mozprofile 09:46:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:46:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:46:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:46:05 INFO - Running setup.py install for mozrunner 09:46:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:46:05 INFO - Running setup.py install for mozscreenshot 09:46:06 INFO - Running setup.py install for moztest 09:46:06 INFO - Running setup.py install for mozversion 09:46:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:46:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:46:06 INFO - Cleaning up... 09:46:06 INFO - Return code: 0 09:46:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:06 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:46:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:46:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:46:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:46:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:06 INFO - 'HOME': '/Users/cltbld', 09:46:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:06 INFO - 'LOGNAME': 'cltbld', 09:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:06 INFO - 'MOZ_NO_REMOTE': '1', 09:46:06 INFO - 'NO_EM_RESTART': '1', 09:46:06 INFO - 'PAGER': '/bin/cat', 09:46:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:06 INFO - 'PWD': '/builds/slave/test', 09:46:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:06 INFO - 'SHELL': '/bin/bash', 09:46:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:46:06 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:46:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:06 INFO - 'USER': 'cltbld', 09:46:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:06 INFO - 'XPC_FLAGS': '0x0', 09:46:06 INFO - 'XPC_SERVICE_NAME': '0', 09:46:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:46:06 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-EekGOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:46:06 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-2TyQV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:46:06 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-XnJ1eI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:46:06 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-zgrWGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-rAU6Kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-Hwg0e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-iFHDqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-JGJeAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:46:07 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)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-RCDLB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-3IPSs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-T7AJN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-7_2TyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-TeJCHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:46:07 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-6qojwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:08 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-O6rYJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:46:08 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-dCIqSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:46:08 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-VE18_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:46:08 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)) 09:46:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:09 INFO - Downloading blessings-1.6.tar.gz 09:46:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:46:09 INFO - Installing collected packages: blessings 09:46:09 INFO - Running setup.py install for blessings 09:46:10 INFO - Successfully installed blessings 09:46:10 INFO - Cleaning up... 09:46:10 INFO - Return code: 0 09:46:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:46:10 INFO - Reading from file tmpfile_stdout 09:46:10 INFO - Current package versions: 09:46:10 INFO - blessings == 1.6 09:46:10 INFO - blobuploader == 1.2.4 09:46:10 INFO - docopt == 0.6.1 09:46:10 INFO - functools32 == 3.2.3-2 09:46:10 INFO - jsonschema == 2.5.1 09:46:10 INFO - manifestparser == 1.1 09:46:10 INFO - mozInstall == 1.13 09:46:10 INFO - mozcrash == 1.0 09:46:10 INFO - mozdebug == 0.1 09:46:10 INFO - mozdevice == 0.51 09:46:10 INFO - mozfile == 1.2 09:46:10 INFO - mozhttpd == 0.7 09:46:10 INFO - mozinfo == 0.10 09:46:10 INFO - mozleak == 0.1 09:46:10 INFO - mozlog == 3.4 09:46:10 INFO - moznetwork == 0.27 09:46:10 INFO - mozprocess == 0.25 09:46:10 INFO - mozprofile == 0.28 09:46:10 INFO - mozrunner == 6.13 09:46:10 INFO - mozscreenshot == 0.1 09:46:10 INFO - mozsystemmonitor == 0.3 09:46:10 INFO - moztest == 0.8 09:46:10 INFO - mozversion == 1.4 09:46:10 INFO - psutil == 3.1.1 09:46:10 INFO - requests == 1.2.3 09:46:10 INFO - wsgiref == 0.1.2 09:46:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:10 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:46:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:46:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:46:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:10 INFO - 'HOME': '/Users/cltbld', 09:46:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:10 INFO - 'LOGNAME': 'cltbld', 09:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:10 INFO - 'MOZ_NO_REMOTE': '1', 09:46:10 INFO - 'NO_EM_RESTART': '1', 09:46:10 INFO - 'PAGER': '/bin/cat', 09:46:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:10 INFO - 'PWD': '/builds/slave/test', 09:46:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:10 INFO - 'SHELL': '/bin/bash', 09:46:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:46:10 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:46:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:10 INFO - 'USER': 'cltbld', 09:46:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:10 INFO - 'XPC_FLAGS': '0x0', 09:46:10 INFO - 'XPC_SERVICE_NAME': '0', 09:46:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:46:10 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:46:12 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:46:12 INFO - Installing collected packages: marionette-driver 09:46:12 INFO - Running setup.py install for marionette-driver 09:46:12 INFO - Successfully installed marionette-driver 09:46:12 INFO - Cleaning up... 09:46:12 INFO - Return code: 0 09:46:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:46:12 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 0x100f85c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f89768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f880b582970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, '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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:46:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:46:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:46:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:12 INFO - 'HOME': '/Users/cltbld', 09:46:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:12 INFO - 'LOGNAME': 'cltbld', 09:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:12 INFO - 'MOZ_NO_REMOTE': '1', 09:46:12 INFO - 'NO_EM_RESTART': '1', 09:46:12 INFO - 'PAGER': '/bin/cat', 09:46:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:12 INFO - 'PWD': '/builds/slave/test', 09:46:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:12 INFO - 'SHELL': '/bin/bash', 09:46:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:46:12 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:46:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:12 INFO - 'USER': 'cltbld', 09:46:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:12 INFO - 'XPC_FLAGS': '0x0', 09:46:12 INFO - 'XPC_SERVICE_NAME': '0', 09:46:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:46:12 INFO - Cleaning up... 09:46:12 INFO - Return code: 0 09:46:12 INFO - Running post-action listener: _resource_record_post_action 09:46:12 INFO - Running post-action listener: _start_resource_monitoring 09:46:12 INFO - Starting resource monitoring. 09:46:12 INFO - [mozharness: 2017-06-11 16:46:12.719409Z] Finished create-virtualenv step (success) 09:46:12 INFO - [mozharness: 2017-06-11 16:46:12.720386Z] Running install step. 09:46:12 INFO - Running pre-action listener: _resource_record_pre_action 09:46:12 INFO - Running main action method: install 09:46:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:46:12 INFO - Reading from file tmpfile_stdout 09:46:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:46:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:46:13 INFO - Reading from file tmpfile_stdout 09:46:13 INFO - Output received: 09:46:13 INFO - Usage: mozinstall [options] installer 09:46:13 INFO - Options: 09:46:13 INFO - -h, --help show this help message and exit 09:46:13 INFO - -d DEST, --destination=DEST 09:46:13 INFO - Directory to install application into. [default: 09:46:13 INFO - "/builds/slave/test"] 09:46:13 INFO - --app=APP Application being installed. [default: firefox] 09:46:13 INFO - mkdir: /builds/slave/test/build/application 09:46:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:46:35 INFO - Reading from file tmpfile_stdout 09:46:35 INFO - Output received: 09:46:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:46:35 INFO - Running post-action listener: _resource_record_post_action 09:46:35 INFO - [mozharness: 2017-06-11 16:46:35.030853Z] Finished install step (success) 09:46:35 INFO - [mozharness: 2017-06-11 16:46:35.030980Z] Running setup-mitmproxy step. 09:46:35 INFO - Running pre-action listener: _resource_record_pre_action 09:46:35 INFO - Running main action method: setup_mitmproxy 09:46:35 INFO - Skipping: mitmproxy is not required 09:46:35 INFO - Running post-action listener: _resource_record_post_action 09:46:35 INFO - [mozharness: 2017-06-11 16:46:35.031737Z] Finished setup-mitmproxy step (success) 09:46:35 INFO - [mozharness: 2017-06-11 16:46:35.031880Z] Running run-tests step. 09:46:35 INFO - Running pre-action listener: _resource_record_pre_action 09:46:35 INFO - Running main action method: run_tests 09:46:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:46:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:46:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:46:35 INFO - 2017-06-11 09:46:35.046 screenresolution[1654:14903] starting screenresolution argv=screenresolution get 09:46:35 INFO - 2017-06-11 09:46:35.049 screenresolution[1654:14903] Display 0: 1600x1200x32@60 09:46:35 INFO - 2017-06-11 09:46:35.055 screenresolution[1655:14905] starting screenresolution argv=screenresolution list 09:46:35 INFO - Available Modes on Display 0 09:46:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:46:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:46:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:46:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:46:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:46:35 INFO - Intel Iris: 09:46:35 INFO - Chipset Model: Intel Iris 09:46:35 INFO - Type: GPU 09:46:35 INFO - Bus: Built-In 09:46:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:46:35 INFO - Vendor: Intel (0x8086) 09:46:35 INFO - Device ID: 0x0a2e 09:46:35 INFO - Revision ID: 0x0009 09:46:35 INFO - Displays: 09:46:35 INFO - Display: 09:46:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:46:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:46:35 INFO - Display Serial Number: 1600x1200 60 09:46:35 INFO - Main Display: Yes 09:46:35 INFO - Mirror: Off 09:46:35 INFO - Online: Yes 09:46:35 INFO - Rotation: Supported 09:46:35 INFO - Return code: 0 09:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:46:35 INFO - Python 2.7.3 09:46:35 INFO - Return code: 0 09:46:35 INFO - grabbing minidump binary from tooltool 09:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:46:35 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 0x100cdbd30>, '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 0x100ca5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cccbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:46:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:46:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:46:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:46:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:46:35 INFO - Return code: 0 09:46:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:46:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:46:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:46:35 INFO - ENV: RUST_BACKTRACE is now 1 09:46:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:46:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:46:35 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/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:46:35 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/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0363 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TChSH8hQ1I/Render', 09:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:35 INFO - 'HOME': '/Users/cltbld', 09:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:35 INFO - 'LOGNAME': 'cltbld', 09:46:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:46:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:35 INFO - 'MOZ_NO_REMOTE': '1', 09:46:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:46:35 INFO - 'NO_EM_RESTART': '1', 09:46:35 INFO - 'PAGER': '/bin/cat', 09:46:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:35 INFO - 'PWD': '/builds/slave/test', 09:46:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:35 INFO - 'RUST_BACKTRACE': '1', 09:46:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:46:35 INFO - 'SHELL': '/bin/bash', 09:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lwOuXHEaxF/Listeners', 09:46:35 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 09:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:35 INFO - 'USER': 'cltbld', 09:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:35 INFO - 'XPC_FLAGS': '0x0', 09:46:35 INFO - 'XPC_SERVICE_NAME': '0', 09:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:35 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/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:46:35 INFO - mozversion application_buildid: 20170611083515 09:46:35 INFO - mozversion application_changeset: 981da978f1f686ad024fa958c9d27d2f8acc5ad0 09:46:35 INFO - mozversion application_display_name: Nightly 09:46:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:46:35 INFO - mozversion application_name: Firefox 09:46:35 INFO - mozversion application_remotingname: firefox 09:46:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:46:35 INFO - mozversion application_vendor: Mozilla 09:46:35 INFO - mozversion application_version: 55.0a1 09:46:35 INFO - mozversion platform_buildid: 20170611083515 09:46:35 INFO - mozversion platform_changeset: 981da978f1f686ad024fa958c9d27d2f8acc5ad0 09:46:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:46:35 INFO - mozversion platform_version: 55.0a1 09:46:35 INFO - using testdate: 1497199595 09:46:35 INFO - actual date: 1497199595 09:46:35 INFO - starting webserver on 'localhost:49229' 09:46:35 INFO - SUITE-START | Running 2 tests 09:46:35 INFO - TEST-START | dromaeo_css 09:46:35 INFO - operating with platform_type : mac_ 09:46:35 INFO - Initialising browser for dromaeo_css test... 09:46:35 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmp1ZmTW_/profile 09:46:35 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 09:47:09 INFO - TEST-INFO | 1665: killed by SIGTERM 09:47:09 INFO - Browser initialized. 09:47:09 INFO - Running cycle 1/1 for dromaeo_css test... 09:47:09 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmp1ZmTW_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:47:11 INFO - PID 1673 | 2017-06-11 09:47:11.375 plugin-container[1674:15409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 09:47:11 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:11 INFO - PID 1673 | 2017-06-11 09:47:11.376 plugin-container[1674:15409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c1b, name = 'com.apple.CFPasteboardClient' 09:47:11 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:11 INFO - PID 1673 | 2017-06-11 09:47:11.376 plugin-container[1674:15409] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:12 INFO - PID 1673 | 2017-06-11 09:47:12.255 plugin-container[1675:15455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 09:47:12 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:12 INFO - PID 1673 | 2017-06-11 09:47:12.256 plugin-container[1675:15455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 09:47:12 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:47:12 INFO - PID 1673 | 2017-06-11 09:47:12.256 plugin-container[1675:15455] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:47:21 INFO - PID 1673 | RSS: Main: 252690432 09:47:21 INFO - PID 1673 | 09:50:24 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 09:50:26 INFO - PID 1673 | RSS: Main: 274702336 09:50:26 INFO - PID 1673 | 09:53:28 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 09:53:28 INFO - PID 1673 | RSS: Main: 281448448 09:53:28 INFO - PID 1673 | 09:56:30 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 09:56:31 INFO - PID 1673 | RSS: Main: 284389376 09:56:31 INFO - PID 1673 | 09:59:32 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 09:59:33 INFO - PID 1673 | RSS: Main: 287043584 09:59:33 INFO - PID 1673 | 10:02:36 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 10:02:37 INFO - PID 1673 | RSS: Main: 288714752 10:02:37 INFO - PID 1673 | 10:05:38 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 10:05:39 INFO - PID 1673 | RSS: Main: 286674944 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | __start_tp_report 10:05:39 INFO - PID 1673 | _x_x_mozilla_page_load 10:05:39 INFO - PID 1673 | _x_x_mozilla_page_load_details 10:05:39 INFO - PID 1673 | |i|pagename|runs| 10:05:39 INFO - PID 1673 | |0;dojo.html;3098.426791043657;3456.498807672889;3596.910077248067;3597.730170237233;3605.1167463971324;6075.739904502731;6097.085437184424;6137.009281402744;6178.907316390258;6196.194494715686;6201.627902325864;6213.782517611885;6226.221722284715;6248.718807653655;6258.217722784652;5426.403095659474;5427.674339539621;5443;5454.754536045877;5554.777808887639;5473.042217611922;5491.450854914517;5530.142827861688;5531.529819965308;5562.415946325638;5293.841184764463;5337.6797392156595;5395.487428694268;5500.28496295481;5505.834824955277;5376.274202982587;5390.110631745796;5436.728163591804;5448.91826622601;5483.75323110457;5356.11624082026;5389.083855744533;5406.567474602061;5411.594130440233;5464.890702185934;6019.518438524908;6219.160413344186;6230.127782110508;6231.6572588507615;6231.87536249277;6712.194536655584;6748.865022699568;6762.729490820361;6782.559133656297;6792.490563207779;6610.7025183866845;6710;6726.024726414643;6840.179178498612;6854.554453960476;5198.402183748842;6239.313675495692;6269.840079585291;6329.145565348619;6348.841278968017;5731.283589551306;6929.341712537301;6951.270116637781;6954.860902782018;6961.199462061826;6424.3254458282145;6430.967845160838;6466.870662586722;6677.799666010027;6681.465482761368;6588;6629.7348106075215;6641.501887358468;6687.665616719145;6692.86614267712;6705.664716764142;6717.361850624183;6730.461563074942;6734.8989765153565;6763.492738044666;6703.128593282842;6706.798796036127;6728;6738.865222695518;6765.898511522331;5199.428062913076;5243.003829272426;5270.657407268515;5283.815066472655;5327.147656374961;4276.914461710749;4603.286490593963;4697.248440249544;4767.785449654774;4782.258749893772;5647.37878833328;6160.9383906160365;6198.239907405552;6206.813795586049;6248.4064013918605;5728.398518155617;6821.590704557742;6854.314568543105;6858.554193977476;6895.862082758316;5232.686038837682;6249.500039996789;6263.592866463665;6272.153259309935;6276.121343011982;5874.471297583238;5963.105534169822;5967.373425790316;5976.103584462366;5981.953158197298;6455;6504.15445992018;6736.292689267752;6822.556533825484;6824.727010919508;6664.866702666017;6721.126253587003;6804.149481314836;6808.285130061272;6819.488538359742;5783.306003279633;6876.759313424006;6933.410660093747;6958.860822783618;6972.6862291196;6725.9663701681175;6730;6752.020956961312;6752.729890804313;6766.289539598468;6778.4577233822165;6830.624315662488;6837.384635382839;6842.55523390978;6844.555103918229;5481.917771233514;6322.683865806599;6404.071409645669;6408.487321014214;6653.534252602271;6508.349165083454;6566.704498297684;6585.209774827052;6598.94416893314;6610.305917878553;6366.363363663597;6422.678866056772;6451.806445806634;6473.1261279728515;6492.870142597216;6380.521460890544;6602.1747281589805;6630.7679231226675;6648.202215734143;6654.401103900672;6285.214348206474;6297.685115744102;6302.527310451643;6332.081848131903;6332.778352757631 10:05:39 INFO - PID 1673 | |1;ext.html;737.5316673912066;741.1143683298457;829.0507369062422;843.8143608406152;1059.819830628793;36364.63635363649;37102.628973710256;37208;37254;37263.88208353747;37635;37711.86864394066;37901.81049094751;37931.81034094832;38036.85889423315;7683.3853291736805;7813.335866451358;7823.3350165236025;7848.960755196216;7857.096433910087;7992.680292788311;8138.837223255531;8218.219269169449;8225.876611850827;8253.628586713583;8246.587670616474;8266.090730019723;8290.212429819157;8313.043999940024;8316.37627177964;8230.218129277739;8230.876536851953;8286.875696864552;8295.585220738969;8317.376196785204;8031.196880311981;8044.718434854752;8048.476849004796;8072.394570407243;8075.919240807575;32475;35240.06179660118;35513;35816.820915895514;35850.64149358525;3057.4190903728445;3106.3942531206426;3562.928741425157;3585.4801053847045;3586.497890295361;25277.49445011108;26534.73465265342;26689;29234.269143271376;29298;3027.7275045245815;3032.135841285245;3040;3056.9388612227854;3070.1096681962204;237836;238059;240569.79715101488;295837.5624365636;296105;37144;37546.87359379223;45222;45319.773401132785;45354.546454535695;48112.27831582529;48311.75844120757;48413.75793121082;48463.788405289444;48477;13727.656808579866;13837.238951857544;13839.377228024561;13865.792013119812;13898.930505347409;11075.335479871233;11220.158488113424;11242.49408776607;11243.831342529706;11259.930306620858;11176.720581985353;13208.207507549385;13234.875035621888;13236.338183090807;13260.336983150804;5770.076787713946;5770.855728606818;5848.473637372657;5848.941510584925;5850.444207800252;964.2334344196381;970.6214576315243;970.7932698171126;1008.495752123938;1024.3239461955075;22212.666809997863;22226.222082227043;22429.542079764764;23136;30450.390992180033;1011.4234886114991;1118.8881111888745;1129.1982692288402;1138.214631904;1143.2968724234536;12330;12345.135840491079;12349.444275007647;12354;12361.690957726129;324220;326335;326376.3681181579;326543;326882;52661.47338526642;53101.7344913273;53195.46804531905;53290.467095329324;53781;1240.5347994502063;1532.1879403915943;1539.3226980128707;1541.2756004677783;1542.2597153366223;21786.237481688066;22153.556928861653;22854.200102996314;22880.970356333673;23505.17731879376;38329.80835095807;38541;38615;38632.034199145244;38746.6438674645;218677;294649;294822;295213;296140;28230.85884570646;28813;28906.566401503573;34673.30653386968;34870;32336.676633234307;38408.807955960045;40009.79995100006;42013.78993105015;42198.367024495274;22931.88534057319;26990.325241868326;27534.311642209104;28573.142805715863;29369.7063029367;28021.43957120887;28679.856600716863;29038.709612904444;29238.26904327409;29694.406111878074;33134.502982455764;34595;34623;34649;34753.478697819046;27299.590506142027;27916.860415697793;28331.575026375034;29250.561241580966;29562;18103.642226833304;23163.420914477272;23393.883030584737;23399.532009360057;23528.882355588114 10:05:39 INFO - PID 1673 | |2;jquery.html;416.8103943888696;433.65957723187296;440.889777555611;485.8226747237259;515.9509846564575;20784.96075196242;20992.84539350335;21485.462863428424;21643.026063827125;21663.783362166374;18720.625587488277;23064.769352306474;24591.87704061477;24691.888865001074;24708.876455617698;6316.621002739827;6397.392247736481;6462.903056454157;6465.6767161641965;6467.902981455282;5791.362950075489;6249.5625306228585;6357.68211589421;6386.808395748112;6424.9036264455835;5417.51242388185;5558;5814.941850581504;6311.589746666475;6390.456811171056;6284.183056202687;6338.492920566344;6338.904916426257;6343.365663433666;6346.873062538746;5753.280839895013;5768.596198266081;5780.248567686216;5785.537157027427;5788.768449262025;25259.74740252592;27715.445691086064;30484.170949743086;30747.38505229883;30771.846140769376;1989;2211.1708109458955;2227.587896239185;2255.740589832174;2259.548090381914;15709.21453927299;18582.721259181122;18924.81075189258;19078.904605477022;19138.330158444387;2178.0525471419983;2215.0475295622878;2262.0951619352227;2262.6379779235244;2266.5693518231483;194237;207284;209828;211661.94169029055;211907;15097.924510377377;15112.773308400383;15172.544823655307;15213.695726085416;15401.150787104198;20057.896815675278;20120.19519219215;20121.899390503248;20126.798732012492;20194.293199738233;9682.75793105178;9730.221582273402;9770.12068913787;9848.409095454295;10091.798164036678;7963.203679631991;9033.864492032624;9068.637254509878;9105.590248438835;9158.40470369424;9108.681196158102;9235.199136168518;9246.445213287225;9272.211861991671;9292.39599426035;2434.2332165368207;2462.827602067874;2579.9871000644875;2593.390553219992;2595.5587550116147;926.249737712455;930.6742640076054;934.9719508414759;982.3172894838161;990.4750482244422;18228.45314640563;20750.481237969172;20845.58308833815;20920.47698807542;22122.668159977613;950.9075501074017;955.9187469065068;1000.5097502223858;1002.6240159940022;1014.1177175856905;10573.577056917637;10752.784944301227;10841.620543280947;10851.403172825412;11126.721831954266;550402;550904;576468;578293;584920;152459.71310430352;158627;160554;165428;165990;1150.6326092146096;1165.4988355007429;1220.8840160184768;1226.8957138643136;1231.1566576894857;21923.780762192175;22285.77714222837;22990.540189196458;23085.769142308363;23773.64339534943;60556;63308.36691633209;63550.68224658847;63693.68153159205;64233.036504453405;66009.66995164994;86626;89514.55242723745;89845.10154898367;89855.55072224597;11341.319520828776;11684.941575292409;11689.240199387013;11818.645440636794;11884.227525210834;15377.538673839803;18168.182431790337;18290.26838926428;18442;18531.166097525915;13733.176009439465;14320.355583998537;14438.639034024234;14493.565193044225;14520.564383068524;10976.780464390507;13055.804162937753;13135.343232838512;13298.86701133015;13626.386812593259;14208;15290.159041252839;15333.463328783439;15459.768103478682;15478.5356439307;11587.42062896835;11693.356865372487;11702.531898723955;11749.412529373327;11766.58816941403;13638.727225455634;14532.128072315694;14758.188299643623;14799.038062525902;14809.85190148128 10:05:39 INFO - PID 1673 | |3;mootools.html;1371.451419432227;1407.1838333766416;1418.6735402398751;1446.9059511131768;1450.216882883243;13052.543160989368;13453.730925381487;13584.388702508386;13596;13627.523036693718;12656.557020504284;12887.871121288785;13414.26221557814;13431.932840335785;13448;2674.6522952016257;2725.9182224533297;2742.684591272009;2745.574435962419;2749.4363655450684;2591.9740802592014;2718.4698983698186;2721.4421043685998;2722.68689100753;2728.304282407983;2260.5026894083276;2344.402177444749;2447.620618804079;2685.8119931604797;2725.059854350246;2657.574788033915;2659.0427446119384;2663.6670416197976;2671.7461841125064;2673.625692403065;2641.867906604662;2654;2657.694365147994;2658.601209818543;2726.332048648094;13079.28063956482;13111.737765244738;13167.868321316855;13216.140950838164;13223.206607603575;7260.564366138048;7483;7537.962310188469;7538.773836784905;7543.207963163899;3880.2433525462548;4482.372467854503;4631.212693842055;4735.952640473585;4744.383230180055;1390.4507719450862;1396.190209678384;1396.322783450026;1420.2188796162172;1427.1651084115804;17638.647227055386;18101.366452174243;19512.024398780006;19635.509112272164;19653.606927861507;5042.142835717937;5217.947820521822;6096.664683442454;6450.580712253689;6495.610263384117;13907;13958.511452099128;13968.790468142986;13980.720385592434;14000.929995350161;7333.376662983706;7348.926510734825;7357.153927298429;7386;7390.556566606021;6777.525573209927;6816.863662726718;6872.10662613857;6957.965210174018;6963.512554121163;4907.779149938261;5048.545630893237;5068.189089745623;5085.8728531787;5086.440491545927;1393.770027945398;1407.5917983784793;1410.8659677330638;1424.985750142506;1447.7176950494552;750.1785544828405;754.8754455514888;828.2669837194127;838.4089217101929;860.2902605350611;6873.20958089816;7102.970069339916;7161.89257161143;7240.674169662378;7285.307895749896;613.5367797313;652.455199908082;684.2678334182377;716.5485743981362;718.791550450374;3661.6704496595435;4111.198316328347;4174.3947127666315;4321.0709697415205;4372.978135109368;17297.65404691899;17772.289108435776;17926.372576960006;17964.550886227687;17996.920184788956;17590.856594321584;17684.823151768574;17706.114694265234;17795.822041779677;17967.910160449115;9293.163615274658;9433.528323583794;9463.574139163755;9465.337426380225;9478.241740660731;6422.871542569216;6633.209033561008;7630.923690763021;7771.533707977539;8113.18868113184;14721.852781472047;15809.68380632404;16109.677806443571;16297.837021629632;16459.34162633481;16140.273687684317;16454.84816062864;16691.74962375541;16812.907161034494;17167.57081072983;10035.54840032215;10621.628243011457;10622.415767132881;10712.035916767529;10720.195985300978;12226.510939562319;12302.446389912297;12332.691682708004;12365.75268494643;12370.81443778362;9461.773136673743;9637.951810240904;9954.950225248827;10137.949310253402;10163.898361016296;9437.858432123661;9450.196733277748;9520.143187113194;9807.656732014346;10020.24848136378;8590.398672092893;8680.609372578376;8697.304215662858;8701.17338852808;8740.08229135957;6174.969125154345;6180;6244.937550624436;6512.641804700787;6528.347165283433;6658.900116498159;6672.4328432081875;6878.484113691393;6888.793336199721;6988.336108069725 10:05:39 INFO - PID 1673 | |4;prototype.html;392.60935369307543;431.43912913212813;435.7776437093954;444.10734423808145;468.3723810094473;5183.974080129598;5222.764975576094;5680.659160450371;6538.248101468333;6563.934360656392;4765.618750499961;5572.693501857396;6125.418085281903;6377.521685873567;6393.968030159855;4154.543000269968;4911.901761964758;4932.027189124361;4951.0592987332475;4983.975080124613;4969.329140566014;5016.623753218505;5024.82413115541;5026.623003274751;5038.118329292359;4246.363045543162;4481.753503557303;4906.950930490685;4933.827316043939;4964.354633897607;4110.794460276974;4553.2714765637675;4924.778384972685;4945.851624451254;5007.824726134604;4181.749095054306;4196.790160491963;4203.97898010511;4215.3887686285625;4220.957790422087;6234.3453937336835;6263.655498947556;6265.154204182422;6275.6548389838545;6332.461740752042;4717.622590192776;5559.916601250984;5705.286839145107;5749.7700091996685;5780.971095144498;5530.336359636829;5732.369439361667;6073.878522429527;6895.89665653493;6919.723211071601;4805.711657300538;4807.711537307773;4812.639052071108;4819.95180048202;4825.659555115766;6933.37599616037;6963.65181740916;7244.927550724478;7256.875184346382;7391.408687305003;4908.9754551227015;4912.263160525949;4917.918058027229;4926.6551341406475;4927.655064145475;4774.904501910012;4993.650444468853;5007.874803129878;5009.223570346602;5009.499050095034;5704.857378565486;5709.714514274353;5712.685802280831;5719.369090218463;5733.226014488074;5550.91673624896;5768.451997060272;6986.790396288119;7028.332308430691;7029.859402811915;1705.4713038957964;1722.7415887616958;1729.3428497170994;1733.4106403822761;1748.8163742807076;908.418612088264;1086.1582273737758;1142.2232881640564;1143.3597185576104;1148.385613696685;5205;5850;6207.193064901534;6425.646589437626;6429;5952;6816.556923799937;7048.259932707095;7057.4001209896705;7093.290670932969;6474.514411419163;6570.901436478457;6572.9247866323185;6577.736890524421;6577.967110164419;3462.7576069674883;3749.8750374887422;3755.75587586806;3791.33651610967;3811.733178677521;6310.242770867433;7049.859002819915;7096.7516136934955;7228.855422891513;7324.597147156851;5309.495597918191;6429.903551446732;6500.6424646643945;7356.77929662111;7413.2957369049855;6365.427111559889;6430.64631445275;6718.924972004455;7230.927690723131;7391.8152046199275;6303.684815759286;7173.605451700207;7180.630391970742;7183.712651493882;7211.855762884608;7020.929790702027;7041.753538626123;7088.64556772149;7091.468139889632;7100.4319654428555;7333.779986600411;7346.045014148127;7346.96326518364;7369.557826530426;7413.888791668237;4849.903001940012;4858.975705121452;4882.584980276716;4900.166971614763;4913.164761990399;5979.4917432018765;6004.979153543995;6830.394857208453;7093.432525398059;7166.319199676022;5960.582759206987;6160.260768707604;6740.79777606653;6929.896051559332;6969;6265.310815810349;6427.710753016032;6888.862222755617;6893.7931862044215;6905.205901321412;5671.773129074791;6217.906731398942;6777.559458635173;6801.625910574768;6845.452363810983;6751.594904305757;6841;6923.515353925177;6925.549839260432;6928.480363972823;6722.722682690273;6726.461883049442;6755.527113102035;6756.797296081126;6801.829954251183 10:05:39 INFO - PID 1673 | |5;yui.html;764.7094104240388;772.8763397856344;822.6837060702874;824.7649419915325;864.5201912938321;13861;16796.664066718673;16926.322947082102;16953.745693814588;17195.656086878254;12769.93615031926;13388.933055334734;13407.128536645112;13438.664033399169;14018.719625607482;643.6427782580678;792.4492477727981;928;930.9674161404319;931.4132096779019;952.2382094324513;960.3037797596721;966.8888077871029;972.9027097290285;974.2692980264801;1004.7337455574278;1005.9446730429823;1007.2244371833684;1009.4548943570463;1011.2163073617932;668.1314291421157;684.3737979748524;692.5152393324693;1007.0885848307294;1009.3136667066392;711.9252478489788;731.3016069653521;732.5092188233842;736.5065406177873;738.1326940844486;14625.195614241213;16218.513444596681;16409.83590164095;16543.255553500123;16589.83410165895;16354;16865;17329.393471228446;17451.738223926524;17610.383636572788;14184.85815141856;14318.64203394913;14395.568132956028;14458.49395271171;14634;3447.120984149051;3452.067941655739;3479.895603131885;3480.269143479872;3483.4078206704667;94320.0567994325;138416;143059.28470357685;143712;143987;11026.669199924016;11158.386288754196;13067.215967042008;14023.859761402255;14272.785908211385;22653.660195097087;25879.0942317018;25929.870350648504;25968.740312596634;25972.870135649202;7965.681372745025;7987.560684162427;8012.3590112790835;8096.230858068474;8112.918870811334;3888.4556162137324;3976.2842688315804;3980.522337319482;3985.322495175827;3990.2618015667194;3892.727509074338;4616.0767846430845;4620.330052142488;4622.514635963243;4634.907301854011;985.6698006168014;1031.8142734307896;1036.7356324137297;1038.231708535678;1039.1478987230398;631.4601016131141;646.9741210351533;648.5103746671233;671.6037537852691;678.3792829560897;9870.802583948425;11145.442727863736;12157.148999570121;12371.69070773238;12459.866387476392;616.8185201220041;637.5250438423404;670.8960111182774;672.042339665974;677.4986509982577;5071.3153724247495;5498.697571633518;5515.4760297771645;5615.9157612635845;5840.027524719888;140651;144780.27609861884;147560.52439475682;149645;152194;104865.42701859478;122520;124772;125320;131097;106505;110151;110740.446297768;110797.99999999838;115395.69208615948;10667.199960002876;11236.94381528087;11855.17013809025;11996.820047699117;12765.553205638129;41431;43891.78054109709;45601.77199114116;46623.40878696266;49978.25032624441;63896.68051659712;83891.98891793916;85745.14254857371;91336.543317283;91728;15887.443939462064;16034.118123502853;16150.03099814015;16152.919235403748;16181.433649822671;17884.91057544704;18085;18445.81554184441;18590;19043.42869713911;17393.956362618283;17521.649567008335;17728.556786079935;18028.918264904147;18232.17955192046;16705.582360441087;17406.564835879206;17509.56226094307;17604.91197544004;17738.822611773718;16730.4144354947;16958.982461052376;17027.574310641838;17265.56836079108;17578.296868125133;11252.60615878473;11842.76314473723;12138.939305303416;12380.133390662568;12499.56251531192;11912.821307680219;12277.754444910874;13185.736285274432;13503.45986160582;14381 10:05:39 INFO - PID 1673 | __end_tp_report 10:05:39 INFO - PID 1673 | __start_cc_report 10:05:39 INFO - PID 1673 | _x_x_mozilla_cycle_collect,479 10:05:39 INFO - PID 1673 | __end_cc_report 10:05:39 INFO - PID 1673 | __startTimestamp1497200739063__endTimestamp 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | ------- Summary: start ------- 10:05:39 INFO - PID 1673 | Number of tests: 6 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | [#0] dojo.html Cycles:165 Average:6141.64 Median:6332.08 stddev:757.95 (12.0%) stddev-sans-first:721.70 10:05:39 INFO - PID 1673 | Values: 3098.4 3456.5 3596.9 3597.7 3605.1 6075.7 6097.1 6137.0 6178.9 6196.2 6201.6 6213.8 6226.2 6248.7 6258.2 5426.4 5427.7 5443.0 5454.8 5554.8 5473.0 5491.5 5530.1 5531.5 5562.4 5293.8 5337.7 5395.5 5500.3 5505.8 5376.3 5390.1 5436.7 5448.9 5483.8 5356.1 5389.1 5406.6 5411.6 5464.9 6019.5 6219.2 6230.1 6231.7 6231.9 6712.2 6748.9 6762.7 6782.6 6792.5 6610.7 6710.0 6726.0 6840.2 6854.6 5198.4 6239.3 6269.8 6329.1 6348.8 5731.3 6929.3 6951.3 6954.9 6961.2 6424.3 6431.0 6466.9 6677.8 6681.5 6588.0 6629.7 6641.5 6687.7 6692.9 6705.7 6717.4 6730.5 6734.9 6763.5 6703.1 6706.8 6728.0 6738.9 6765.9 5199.4 5243.0 5270.7 5283.8 5327.1 4276.9 4603.3 4697.2 4767.8 4782.3 5647.4 6160.9 6198.2 6206.8 6248.4 5728.4 6821.6 6854.3 6858.6 6895.9 5232.7 6249.5 6263.6 6272.2 6276.1 5874.5 5963.1 5967.4 5976.1 5982.0 6455.0 6504.2 6736.3 6822.6 6824.7 6664.9 6721.1 6804.1 6808.3 6819.5 5783.3 6876.8 6933.4 6958.9 6972.7 6726.0 6730.0 6752.0 6752.7 6766.3 6778.5 6830.6 6837.4 6842.6 6844.6 5481.9 6322.7 6404.1 6408.5 6653.5 6508.3 6566.7 6585.2 6598.9 6610.3 6366.4 6422.7 6451.8 6473.1 6492.9 6380.5 6602.2 6630.8 6648.2 6654.4 6285.2 6297.7 6302.5 6332.1 6332.8 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | [#1] ext.html Cycles:180 Average:43127.93 Median:179405.00 stddev:76560.57 (42.7%) stddev-sans-first:76709.18 10:05:39 INFO - PID 1673 | Values: 737.5 741.1 829.1 843.8 1059.8 36364.6 37102.6 37208.0 37254.0 37263.9 37635.0 37711.9 37901.8 37931.8 38036.9 7683.4 7813.3 7823.3 7849.0 7857.1 7992.7 8138.8 8218.2 8225.9 8253.6 8246.6 8266.1 8290.2 8313.0 8316.4 8230.2 8230.9 8286.9 8295.6 8317.4 8031.2 8044.7 8048.5 8072.4 8075.9 32475.0 35240.1 35513.0 35816.8 35850.6 3057.4 3106.4 3562.9 3585.5 3586.5 25277.5 26534.7 26689.0 29234.3 29298.0 3027.7 3032.1 3040.0 3056.9 3070.1 237836.0 238059.0 240569.8 295837.6 296105.0 37144.0 37546.9 45222.0 45319.8 45354.5 48112.3 48311.8 48413.8 48463.8 48477.0 13727.7 13837.2 13839.4 13865.8 13898.9 11075.3 11220.2 11242.5 11243.8 11259.9 11176.7 13208.2 13234.9 13236.3 13260.3 5770.1 5770.9 5848.5 5848.9 5850.4 964.2 970.6 970.8 1008.5 1024.3 22212.7 22226.2 22429.5 23136.0 30450.4 1011.4 1118.9 1129.2 1138.2 1143.3 12330.0 12345.1 12349.4 12354.0 12361.7 324220.0 326335.0 326376.4 326543.0 326882.0 52661.5 53101.7 53195.5 53290.5 53781.0 1240.5 1532.2 1539.3 1541.3 1542.3 21786.2 22153.6 22854.2 22881.0 23505.2 38329.8 38541.0 38615.0 38632.0 38746.6 218677.0 294649.0 294822.0 295213.0 296140.0 28230.9 28813.0 28906.6 34673.3 34870.0 32336.7 38408.8 40009.8 42013.8 42198.4 22931.9 26990.3 27534.3 28573.1 29369.7 28021.4 28679.9 29038.7 29238.3 29694.4 33134.5 34595.0 34623.0 34649.0 34753.5 27299.6 27916.9 28331.6 29250.6 29562.0 18103.6 23163.4 23393.9 23399.5 23528.9 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | [#2] jquery.html Cycles:180 Average:39766.46 Median:12256.68 stddev:99125.77 (808.7%) stddev-sans-first:99359.81 10:05:39 INFO - PID 1673 | Values: 416.8 433.7 440.9 485.8 516.0 20785.0 20992.8 21485.5 21643.0 21663.8 18720.6 23064.8 24591.9 24691.9 24708.9 6316.6 6397.4 6462.9 6465.7 6467.9 5791.4 6249.6 6357.7 6386.8 6424.9 5417.5 5558.0 5814.9 6311.6 6390.5 6284.2 6338.5 6338.9 6343.4 6346.9 5753.3 5768.6 5780.2 5785.5 5788.8 25259.7 27715.4 30484.2 30747.4 30771.8 1989.0 2211.2 2227.6 2255.7 2259.5 15709.2 18582.7 18924.8 19078.9 19138.3 2178.1 2215.0 2262.1 2262.6 2266.6 194237.0 207284.0 209828.0 211661.9 211907.0 15097.9 15112.8 15172.5 15213.7 15401.2 20057.9 20120.2 20121.9 20126.8 20194.3 9682.8 9730.2 9770.1 9848.4 10091.8 7963.2 9033.9 9068.6 9105.6 9158.4 9108.7 9235.2 9246.4 9272.2 9292.4 2434.2 2462.8 2580.0 2593.4 2595.6 926.2 930.7 935.0 982.3 990.5 18228.5 20750.5 20845.6 20920.5 22122.7 950.9 955.9 1000.5 1002.6 1014.1 10573.6 10752.8 10841.6 10851.4 11126.7 550402.0 550904.0 576468.0 578293.0 584920.0 152459.7 158627.0 160554.0 165428.0 165990.0 1150.6 1165.5 1220.9 1226.9 1231.2 21923.8 22285.8 22990.5 23085.8 23773.6 60556.0 63308.4 63550.7 63693.7 64233.0 66009.7 86626.0 89514.6 89845.1 89855.6 11341.3 11684.9 11689.2 11818.6 11884.2 15377.5 18168.2 18290.3 18442.0 18531.2 13733.2 14320.4 14438.6 14493.6 14520.6 10976.8 13055.8 13135.3 13298.9 13626.4 14208.0 15290.2 15333.5 15459.8 15478.5 11587.4 11693.4 11702.5 11749.4 11766.6 13638.7 14532.1 14758.2 14799.0 14809.9 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | [#3] mootools.html Cycles:180 Average:8028.59 Median:2723.87 stddev:5360.87 (196.8%) stddev-sans-first:5352.57 10:05:39 INFO - PID 1673 | Values: 1371.5 1407.2 1418.7 1446.9 1450.2 13052.5 13453.7 13584.4 13596.0 13627.5 12656.6 12887.9 13414.3 13431.9 13448.0 2674.7 2725.9 2742.7 2745.6 2749.4 2592.0 2718.5 2721.4 2722.7 2728.3 2260.5 2344.4 2447.6 2685.8 2725.1 2657.6 2659.0 2663.7 2671.7 2673.6 2641.9 2654.0 2657.7 2658.6 2726.3 13079.3 13111.7 13167.9 13216.1 13223.2 7260.6 7483.0 7538.0 7538.8 7543.2 3880.2 4482.4 4631.2 4736.0 4744.4 1390.5 1396.2 1396.3 1420.2 1427.2 17638.6 18101.4 19512.0 19635.5 19653.6 5042.1 5217.9 6096.7 6450.6 6495.6 13907.0 13958.5 13968.8 13980.7 14000.9 7333.4 7348.9 7357.2 7386.0 7390.6 6777.5 6816.9 6872.1 6958.0 6963.5 4907.8 5048.5 5068.2 5085.9 5086.4 1393.8 1407.6 1410.9 1425.0 1447.7 750.2 754.9 828.3 838.4 860.3 6873.2 7103.0 7161.9 7240.7 7285.3 613.5 652.5 684.3 716.5 718.8 3661.7 4111.2 4174.4 4321.1 4373.0 17297.7 17772.3 17926.4 17964.6 17996.9 17590.9 17684.8 17706.1 17795.8 17967.9 9293.2 9433.5 9463.6 9465.3 9478.2 6422.9 6633.2 7630.9 7771.5 8113.2 14721.9 15809.7 16109.7 16297.8 16459.3 16140.3 16454.8 16691.7 16812.9 17167.6 10035.5 10621.6 10622.4 10712.0 10720.2 12226.5 12302.4 12332.7 12365.8 12370.8 9461.8 9638.0 9955.0 10137.9 10163.9 9437.9 9450.2 9520.1 9807.7 10020.2 8590.4 8680.6 8697.3 8701.2 8740.1 6175.0 6180.0 6244.9 6512.6 6528.3 6658.9 6672.4 6878.5 6888.8 6988.3 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | [#4] prototype.html Cycles:180 Average:5560.36 Median:6039.43 stddev:1684.09 (27.9%) stddev-sans-first:1643.54 10:05:39 INFO - PID 1673 | Values: 392.6 431.4 435.8 444.1 468.4 5184.0 5222.8 5680.7 6538.2 6563.9 4765.6 5572.7 6125.4 6377.5 6394.0 4154.5 4911.9 4932.0 4951.1 4984.0 4969.3 5016.6 5024.8 5026.6 5038.1 4246.4 4481.8 4907.0 4933.8 4964.4 4110.8 4553.3 4924.8 4945.9 5007.8 4181.7 4196.8 4204.0 4215.4 4221.0 6234.3 6263.7 6265.2 6275.7 6332.5 4717.6 5559.9 5705.3 5749.8 5781.0 5530.3 5732.4 6073.9 6895.9 6919.7 4805.7 4807.7 4812.6 4820.0 4825.7 6933.4 6963.7 7244.9 7256.9 7391.4 4909.0 4912.3 4917.9 4926.7 4927.7 4774.9 4993.7 5007.9 5009.2 5009.5 5704.9 5709.7 5712.7 5719.4 5733.2 5550.9 5768.5 6986.8 7028.3 7029.9 1705.5 1722.7 1729.3 1733.4 1748.8 908.4 1086.2 1142.2 1143.4 1148.4 5205.0 5850.0 6207.2 6425.6 6429.0 5952.0 6816.6 7048.3 7057.4 7093.3 6474.5 6570.9 6572.9 6577.7 6578.0 3462.8 3749.9 3755.8 3791.3 3811.7 6310.2 7049.9 7096.8 7228.9 7324.6 5309.5 6429.9 6500.6 7356.8 7413.3 6365.4 6430.6 6718.9 7230.9 7391.8 6303.7 7173.6 7180.6 7183.7 7211.9 7020.9 7041.8 7088.6 7091.5 7100.4 7333.8 7346.0 7347.0 7369.6 7413.9 4849.9 4859.0 4882.6 4900.2 4913.2 5979.5 6005.0 6830.4 7093.4 7166.3 5960.6 6160.3 6740.8 6929.9 6969.0 6265.3 6427.7 6888.9 6893.8 6905.2 5671.8 6217.9 6777.6 6801.6 6845.5 6751.6 6841.0 6923.5 6925.5 6928.5 6722.7 6726.5 6755.5 6756.8 6801.8 10:05:39 INFO - PID 1673 | 10:05:39 INFO - PID 1673 | [#5] yui.html Cycles:180 Average:25677.24 Median:17515.61 stddev:39693.58 (226.6%) stddev-sans-first:39760.86 10:05:39 INFO - PID 1673 | Values: 764.7 772.9 822.7 824.8 864.5 13861.0 16796.7 16926.3 16953.7 17195.7 12769.9 13388.9 13407.1 13438.7 14018.7 643.6 792.4 928.0 931.0 931.4 952.2 960.3 966.9 972.9 974.3 1004.7 1005.9 1007.2 1009.5 1011.2 668.1 684.4 692.5 1007.1 1009.3 711.9 731.3 732.5 736.5 738.1 14625.2 16218.5 16409.8 16543.3 16589.8 16354.0 16865.0 17329.4 17451.7 17610.4 14184.9 14318.6 14395.6 14458.5 14634.0 3447.1 3452.1 3479.9 3480.3 3483.4 94320.1 138416.0 143059.3 143712.0 143987.0 11026.7 11158.4 13067.2 14023.9 14272.8 22653.7 25879.1 25929.9 25968.7 25972.9 7965.7 7987.6 8012.4 8096.2 8112.9 3888.5 3976.3 3980.5 3985.3 3990.3 3892.7 4616.1 4620.3 4622.5 4634.9 985.7 1031.8 1036.7 1038.2 1039.1 631.5 647.0 648.5 671.6 678.4 9870.8 11145.4 12157.1 12371.7 12459.9 616.8 637.5 670.9 672.0 677.5 5071.3 5498.7 5515.5 5615.9 5840.0 140651.0 144780.3 147560.5 149645.0 152194.0 104865.4 122520.0 124772.0 125320.0 131097.0 106505.0 110151.0 110740.4 110798.0 115395.7 10667.2 11236.9 11855.2 11996.8 12765.6 41431.0 43891.8 45601.8 46623.4 49978.3 63896.7 83892.0 85745.1 91336.5 91728.0 15887.4 16034.1 16150.0 16152.9 16181.4 17884.9 18085.0 18445.8 18590.0 19043.4 17394.0 17521.6 17728.6 18028.9 18232.2 16705.6 17406.6 17509.6 17604.9 17738.8 16730.4 16959.0 17027.6 17265.6 17578.3 11252.6 11842.8 12138.9 12380.1 12499.6 11912.8 12277.8 13185.7 13503.5 14381.0 10:05:39 INFO - PID 1673 | -------- Summary: end -------- 10:05:39 INFO - PID 1673 | 10:05:39 INFO - TEST-INFO | 1673: exit 0 10:05:39 INFO - TEST-OK | dromaeo_css | took 1143970ms 10:05:39 INFO - TEST-START | kraken 10:05:39 INFO - operating with platform_type : mac_ 10:05:39 INFO - Initialising browser for kraken test... 10:05:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpdiak_Q/profile 10:05:39 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 10:06:13 INFO - TEST-INFO | 1681: killed by SIGTERM 10:06:13 INFO - Browser initialized. 10:06:13 INFO - Running cycle 1/1 for kraken test... 10:06:13 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpdiak_Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:15 INFO - PID 1685 | 2017-06-11 10:06:15.570 plugin-container[1686:18073] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b4b, name = 'com.apple.tsm.portname' 10:06:15 INFO - PID 1685 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:15 INFO - PID 1685 | 2017-06-11 10:06:15.570 plugin-container[1686:18073] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b4f, name = 'com.apple.CFPasteboardClient' 10:06:15 INFO - PID 1685 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:15 INFO - PID 1685 | 2017-06-11 10:06:15.570 plugin-container[1686:18073] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:16 INFO - PID 1685 | 2017-06-11 10:06:16.127 plugin-container[1687:18118] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:06:16 INFO - PID 1685 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:16 INFO - PID 1685 | 2017-06-11 10:06:16.127 plugin-container[1687:18118] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 10:06:16 INFO - PID 1685 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:06:16 INFO - PID 1685 | 2017-06-11 10:06:16.127 plugin-container[1687:18118] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:06:25 INFO - PID 1685 | RSS: Main: 253657088 10:06:25 INFO - PID 1685 | 10:06:28 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 10:06:29 INFO - PID 1685 | RSS: Main: 265154560 10:06:29 INFO - PID 1685 | 10:06:31 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 10:06:32 INFO - PID 1685 | RSS: Main: 274812928 10:06:32 INFO - PID 1685 | 10:06:35 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 10:06:36 INFO - PID 1685 | RSS: Main: 277458944 10:06:36 INFO - PID 1685 | 10:06:38 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 10:06:38 INFO - PID 1685 | RSS: Main: 279650304 10:06:38 INFO - PID 1685 | 10:06:40 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:06:41 INFO - PID 1685 | RSS: Main: 280252416 10:06:41 INFO - PID 1685 | 10:06:44 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 10:06:45 INFO - PID 1685 | RSS: Main: 284827648 10:06:45 INFO - PID 1685 | 10:06:48 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 10:06:49 INFO - PID 1685 | RSS: Main: 288104448 10:06:49 INFO - PID 1685 | 10:06:52 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 10:06:53 INFO - PID 1685 | RSS: Main: 286687232 10:06:53 INFO - PID 1685 | 10:06:54 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:06:55 INFO - PID 1685 | RSS: Main: 287096832 10:06:55 INFO - PID 1685 | 10:06:57 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:06:57 INFO - PID 1685 | RSS: Main: 286859264 10:06:57 INFO - PID 1685 | 10:07:00 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:00 INFO - PID 1685 | RSS: Main: 286445568 10:07:00 INFO - PID 1685 | 10:07:03 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:04 INFO - PID 1685 | RSS: Main: 286629888 10:07:04 INFO - PID 1685 | 10:07:07 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:07 INFO - PID 1685 | RSS: Main: 286568448 10:07:07 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 10:07:09 INFO - PID 1685 | RSS: Main: 288415744 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | __start_tp_report 10:07:09 INFO - PID 1685 | _x_x_mozilla_page_load 10:07:09 INFO - PID 1685 | _x_x_mozilla_page_load_details 10:07:09 INFO - PID 1685 | |i|pagename|runs| 10:07:09 INFO - PID 1685 | |0;ai-astar;106;117;114;122;120;165;168;123;119;114 10:07:09 INFO - PID 1685 | |1;audio-beat-detection;138;118;146;134;110;126;109;138;141;138 10:07:09 INFO - PID 1685 | |2;audio-dft;197;198;200;216;214;220;220;198;209;196 10:07:09 INFO - PID 1685 | |3;audio-fft;66;77;75;124;60;57;70;63;128;62 10:07:09 INFO - PID 1685 | |4;audio-oscillator;78;77;110;71;70;83;105;74;74;115 10:07:09 INFO - PID 1685 | |5;imaging-gaussian-blur;97;133;107;137;115;91;77;83;122;136 10:07:09 INFO - PID 1685 | |6;imaging-darkroom;98;129;156;98;112;97;139;154;98;97 10:07:09 INFO - PID 1685 | |7;imaging-desaturate;95;137;139;116;140;147;92;92;87;92 10:07:09 INFO - PID 1685 | |8;json-parse-financial;51;49;52;54;54;54;53;52;52;54 10:07:09 INFO - PID 1685 | |9;json-stringify-tinderbox;55;58;57;64;59;60;58;55;57;59 10:07:09 INFO - PID 1685 | |10;stanford-crypto-aes;79;88;105;83;80;81;81;79;79;83 10:07:09 INFO - PID 1685 | |11;stanford-crypto-ccm;119;124;119;145;161;157;154;154;140;164 10:07:09 INFO - PID 1685 | |12;stanford-crypto-pbkdf2;178;183;177;188;215;173;228;219;176;205 10:07:09 INFO - PID 1685 | |13;stanford-crypto-sha256-iterative;68;66;67;64;66;66;63;69;65;63 10:07:09 INFO - PID 1685 | __end_tp_report 10:07:09 INFO - PID 1685 | __start_cc_report 10:07:09 INFO - PID 1685 | _x_x_mozilla_cycle_collect,1176 10:07:09 INFO - PID 1685 | __end_cc_report 10:07:09 INFO - PID 1685 | __startTimestamp1497200829877__endTimestamp 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | ------- Summary: start ------- 10:07:09 INFO - PID 1685 | Number of tests: 14 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#0] ai-astar Cycles:10 Average:126.80 Median:121.00 stddev:21.49 (17.8%) stddev-sans-first:21.44 10:07:09 INFO - PID 1685 | Values: 106.0 117.0 114.0 122.0 120.0 165.0 168.0 123.0 119.0 114.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:138.00 stddev:13.27 (9.6%) stddev-sans-first:13.74 10:07:09 INFO - PID 1685 | Values: 138.0 118.0 146.0 134.0 110.0 126.0 109.0 138.0 141.0 138.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#2] audio-dft Cycles:10 Average:206.80 Median:211.50 stddev:10.02 (4.7%) stddev-sans-first:9.98 10:07:09 INFO - PID 1685 | Values: 197.0 198.0 200.0 216.0 214.0 220.0 220.0 198.0 209.0 196.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#3] audio-fft Cycles:10 Average:78.20 Median:64.50 stddev:25.99 (40.3%) stddev-sans-first:27.19 10:07:09 INFO - PID 1685 | Values: 66.0 77.0 75.0 124.0 60.0 57.0 70.0 63.0 128.0 62.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:74.00 stddev:17.32 (23.4%) stddev-sans-first:18.15 10:07:09 INFO - PID 1685 | Values: 78.0 77.0 110.0 71.0 70.0 83.0 105.0 74.0 74.0 115.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:107.00 stddev:22.26 (20.8%) stddev-sans-first:23.12 10:07:09 INFO - PID 1685 | Values: 97.0 133.0 107.0 137.0 115.0 91.0 77.0 83.0 122.0 136.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#6] imaging-darkroom Cycles:10 Average:117.80 Median:97.00 stddev:24.54 (25.3%) stddev-sans-first:24.96 10:07:09 INFO - PID 1685 | Values: 98.0 129.0 156.0 98.0 112.0 97.0 139.0 154.0 98.0 97.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#7] imaging-desaturate Cycles:10 Average:113.70 Median:89.50 stddev:24.64 (27.5%) stddev-sans-first:25.19 10:07:09 INFO - PID 1685 | Values: 95.0 137.0 139.0 116.0 140.0 147.0 92.0 92.0 87.0 92.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.50 stddev:1.65 (3.1%) stddev-sans-first:1.66 10:07:09 INFO - PID 1685 | Values: 51.0 49.0 52.0 54.0 54.0 54.0 53.0 52.0 52.0 54.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.50 stddev:2.62 (4.5%) stddev-sans-first:2.51 10:07:09 INFO - PID 1685 | Values: 55.0 58.0 57.0 64.0 59.0 60.0 58.0 55.0 57.0 59.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:81.00 stddev:7.94 (9.8%) stddev-sans-first:8.23 10:07:09 INFO - PID 1685 | Values: 79.0 88.0 105.0 83.0 80.0 81.0 81.0 79.0 79.0 83.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:154.00 stddev:17.40 (11.3%) stddev-sans-first:15.99 10:07:09 INFO - PID 1685 | Values: 119.0 124.0 119.0 145.0 161.0 157.0 154.0 154.0 140.0 164.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.20 Median:196.50 stddev:20.57 (10.5%) stddev-sans-first:20.97 10:07:09 INFO - PID 1685 | Values: 178.0 183.0 177.0 188.0 215.0 173.0 228.0 219.0 176.0 205.0 10:07:09 INFO - PID 1685 | 10:07:09 INFO - PID 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:2.00 (3.0%) stddev-sans-first:1.94 10:07:09 INFO - PID 1685 | Values: 68.0 66.0 67.0 64.0 66.0 66.0 63.0 69.0 65.0 63.0 10:07:09 INFO - PID 1685 | -------- Summary: end -------- 10:07:09 INFO - PID 1685 | 10:07:10 INFO - TEST-INFO | 1685: exit 0 10:07:10 INFO - TEST-OK | kraken | took 91129ms 10:07:10 INFO - SUITE-END | took 1235s 10:07:11 INFO - Completed test suite (00:20:35) 10:07:11 INFO - javascript benchmark 10:07:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8181.902589743217, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3098.426791043657, 3456.498807672889, 3596.910077248067, 3597.730170237233, 3605.1167463971324, 6075.739904502731, 6097.085437184424, 6137.009281402744, 6178.907316390258, 6196.194494715686, 6201.627902325864, 6213.782517611885, 6226.221722284715, 6248.718807653655, 6258.217722784652, 5426.403095659474, 5427.674339539621, 5443.0, 5454.754536045877, 5554.777808887639, 5473.042217611922, 5491.450854914517, 5530.142827861688, 5531.529819965308, 5562.415946325638, 5293.841184764463, 5337.6797392156595, 5395.487428694268, 5500.28496295481, 5505.834824955277, 5376.274202982587, 5390.110631745796, 5436.728163591804, 5448.91826622601, 5483.75323110457, 5356.11624082026, 5389.083855744533, 5406.567474602061, 5411.594130440233, 5464.890702185934, 6019.518438524908, 6219.160413344186, 6230.127782110508, 6231.6572588507615, 6231.87536249277, 6712.194536655584, 6748.865022699568, 6762.729490820361, 6782.559133656297, 6792.490563207779, 6610.7025183866845, 6710.0, 6726.024726414643, 6840.179178498612, 6854.554453960476, 5198.402183748842, 6239.313675495692, 6269.840079585291, 6329.145565348619, 6348.841278968017, 5731.283589551306, 6929.341712537301, 6951.270116637781, 6954.860902782018, 6961.199462061826, 6424.3254458282145, 6430.967845160838, 6466.870662586722, 6677.799666010027, 6681.465482761368, 6588.0, 6629.7348106075215, 6641.501887358468, 6687.665616719145, 6692.86614267712, 6705.664716764142, 6717.361850624183, 6730.461563074942, 6734.8989765153565, 6763.492738044666, 6703.128593282842, 6706.798796036127, 6728.0, 6738.865222695518, 6765.898511522331, 5199.428062913076, 5243.003829272426, 5270.657407268515, 5283.815066472655, 5327.147656374961, 4276.914461710749, 4603.286490593963, 4697.248440249544, 4767.785449654774, 4782.258749893772, 5647.37878833328, 6160.9383906160365, 6198.239907405552, 6206.813795586049, 6248.4064013918605, 5728.398518155617, 6821.590704557742, 6854.314568543105, 6858.554193977476, 6895.862082758316, 5232.686038837682, 6249.500039996789, 6263.592866463665, 6272.153259309935, 6276.121343011982, 5874.471297583238, 5963.105534169822, 5967.373425790316, 5976.103584462366, 5981.953158197298, 6455.0, 6504.15445992018, 6736.292689267752, 6822.556533825484, 6824.727010919508, 6664.866702666017, 6721.126253587003, 6804.149481314836, 6808.285130061272, 6819.488538359742, 5783.306003279633, 6876.759313424006, 6933.410660093747, 6958.860822783618, 6972.6862291196, 6725.9663701681175, 6730.0, 6752.020956961312, 6752.729890804313, 6766.289539598468, 6778.4577233822165, 6830.624315662488, 6837.384635382839, 6842.55523390978, 6844.555103918229, 5481.917771233514, 6322.683865806599, 6404.071409645669, 6408.487321014214, 6653.534252602271, 6508.349165083454, 6566.704498297684, 6585.209774827052, 6598.94416893314, 6610.305917878553, 6366.363363663597, 6422.678866056772, 6451.806445806634, 6473.1261279728515, 6492.870142597216, 6380.521460890544, 6602.1747281589805, 6630.7679231226675, 6648.202215734143, 6654.401103900672, 6285.214348206474, 6297.685115744102, 6302.527310451643, 6332.081848131903, 6332.778352757631], "value": 6089.968010307748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.5316673912066, 741.1143683298457, 829.0507369062422, 843.8143608406152, 1059.819830628793, 36364.63635363649, 37102.628973710256, 37208.0, 37254.0, 37263.88208353747, 37635.0, 37711.86864394066, 37901.81049094751, 37931.81034094832, 38036.85889423315, 7683.3853291736805, 7813.335866451358, 7823.3350165236025, 7848.960755196216, 7857.096433910087, 7992.680292788311, 8138.837223255531, 8218.219269169449, 8225.876611850827, 8253.628586713583, 8246.587670616474, 8266.090730019723, 8290.212429819157, 8313.043999940024, 8316.37627177964, 8230.218129277739, 8230.876536851953, 8286.875696864552, 8295.585220738969, 8317.376196785204, 8031.196880311981, 8044.718434854752, 8048.476849004796, 8072.394570407243, 8075.919240807575, 32475.0, 35240.06179660118, 35513.0, 35816.820915895514, 35850.64149358525, 3057.4190903728445, 3106.3942531206426, 3562.928741425157, 3585.4801053847045, 3586.497890295361, 25277.49445011108, 26534.73465265342, 26689.0, 29234.269143271376, 29298.0, 3027.7275045245815, 3032.135841285245, 3040.0, 3056.9388612227854, 3070.1096681962204, 237836.0, 238059.0, 240569.79715101488, 295837.5624365636, 296105.0, 37144.0, 37546.87359379223, 45222.0, 45319.773401132785, 45354.546454535695, 48112.27831582529, 48311.75844120757, 48413.75793121082, 48463.788405289444, 48477.0, 13727.656808579866, 13837.238951857544, 13839.377228024561, 13865.792013119812, 13898.930505347409, 11075.335479871233, 11220.158488113424, 11242.49408776607, 11243.831342529706, 11259.930306620858, 11176.720581985353, 13208.207507549385, 13234.875035621888, 13236.338183090807, 13260.336983150804, 5770.076787713946, 5770.855728606818, 5848.473637372657, 5848.941510584925, 5850.444207800252, 964.2334344196381, 970.6214576315243, 970.7932698171126, 1008.495752123938, 1024.3239461955075, 22212.666809997863, 22226.222082227043, 22429.542079764764, 23136.0, 30450.390992180033, 1011.4234886114991, 1118.8881111888745, 1129.1982692288402, 1138.214631904, 1143.2968724234536, 12330.0, 12345.135840491079, 12349.444275007647, 12354.0, 12361.690957726129, 324220.0, 326335.0, 326376.3681181579, 326543.0, 326882.0, 52661.47338526642, 53101.7344913273, 53195.46804531905, 53290.467095329324, 53781.0, 1240.5347994502063, 1532.1879403915943, 1539.3226980128707, 1541.2756004677783, 1542.2597153366223, 21786.237481688066, 22153.556928861653, 22854.200102996314, 22880.970356333673, 23505.17731879376, 38329.80835095807, 38541.0, 38615.0, 38632.034199145244, 38746.6438674645, 218677.0, 294649.0, 294822.0, 295213.0, 296140.0, 28230.85884570646, 28813.0, 28906.566401503573, 34673.30653386968, 34870.0, 32336.676633234307, 38408.807955960045, 40009.79995100006, 42013.78993105015, 42198.367024495274, 22931.88534057319, 26990.325241868326, 27534.311642209104, 28573.142805715863, 29369.7063029367, 28021.43957120887, 28679.856600716863, 29038.709612904444, 29238.26904327409, 29694.406111878074, 33134.502982455764, 34595.0, 34623.0, 34649.0, 34753.478697819046, 27299.590506142027, 27916.860415697793, 28331.575026375034, 29250.561241580966, 29562.0, 18103.642226833304, 23163.420914477272, 23393.883030584737, 23399.532009360057, 23528.882355588114], "value": 16714.308803173306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.8103943888696, 433.65957723187296, 440.889777555611, 485.8226747237259, 515.9509846564575, 20784.96075196242, 20992.84539350335, 21485.462863428424, 21643.026063827125, 21663.783362166374, 18720.625587488277, 23064.769352306474, 24591.87704061477, 24691.888865001074, 24708.876455617698, 6316.621002739827, 6397.392247736481, 6462.903056454157, 6465.6767161641965, 6467.902981455282, 5791.362950075489, 6249.5625306228585, 6357.68211589421, 6386.808395748112, 6424.9036264455835, 5417.51242388185, 5558.0, 5814.941850581504, 6311.589746666475, 6390.456811171056, 6284.183056202687, 6338.492920566344, 6338.904916426257, 6343.365663433666, 6346.873062538746, 5753.280839895013, 5768.596198266081, 5780.248567686216, 5785.537157027427, 5788.768449262025, 25259.74740252592, 27715.445691086064, 30484.170949743086, 30747.38505229883, 30771.846140769376, 1989.0, 2211.1708109458955, 2227.587896239185, 2255.740589832174, 2259.548090381914, 15709.21453927299, 18582.721259181122, 18924.81075189258, 19078.904605477022, 19138.330158444387, 2178.0525471419983, 2215.0475295622878, 2262.0951619352227, 2262.6379779235244, 2266.5693518231483, 194237.0, 207284.0, 209828.0, 211661.94169029055, 211907.0, 15097.924510377377, 15112.773308400383, 15172.544823655307, 15213.695726085416, 15401.150787104198, 20057.896815675278, 20120.19519219215, 20121.899390503248, 20126.798732012492, 20194.293199738233, 9682.75793105178, 9730.221582273402, 9770.12068913787, 9848.409095454295, 10091.798164036678, 7963.203679631991, 9033.864492032624, 9068.637254509878, 9105.590248438835, 9158.40470369424, 9108.681196158102, 9235.199136168518, 9246.445213287225, 9272.211861991671, 9292.39599426035, 2434.2332165368207, 2462.827602067874, 2579.9871000644875, 2593.390553219992, 2595.5587550116147, 926.249737712455, 930.6742640076054, 934.9719508414759, 982.3172894838161, 990.4750482244422, 18228.45314640563, 20750.481237969172, 20845.58308833815, 20920.47698807542, 22122.668159977613, 950.9075501074017, 955.9187469065068, 1000.5097502223858, 1002.6240159940022, 1014.1177175856905, 10573.577056917637, 10752.784944301227, 10841.620543280947, 10851.403172825412, 11126.721831954266, 550402.0, 550904.0, 576468.0, 578293.0, 584920.0, 152459.71310430352, 158627.0, 160554.0, 165428.0, 165990.0, 1150.6326092146096, 1165.4988355007429, 1220.8840160184768, 1226.8957138643136, 1231.1566576894857, 21923.780762192175, 22285.77714222837, 22990.540189196458, 23085.769142308363, 23773.64339534943, 60556.0, 63308.36691633209, 63550.68224658847, 63693.68153159205, 64233.036504453405, 66009.66995164994, 86626.0, 89514.55242723745, 89845.10154898367, 89855.55072224597, 11341.319520828776, 11684.941575292409, 11689.240199387013, 11818.645440636794, 11884.227525210834, 15377.538673839803, 18168.182431790337, 18290.26838926428, 18442.0, 18531.166097525915, 13733.176009439465, 14320.355583998537, 14438.639034024234, 14493.565193044225, 14520.564383068524, 10976.780464390507, 13055.804162937753, 13135.343232838512, 13298.86701133015, 13626.386812593259, 14208.0, 15290.159041252839, 15333.463328783439, 15459.768103478682, 15478.5356439307, 11587.42062896835, 11693.356865372487, 11702.531898723955, 11749.412529373327, 11766.58816941403, 13638.727225455634, 14532.128072315694, 14758.188299643623, 14799.038062525902, 14809.85190148128], "value": 11549.88133763829, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.451419432227, 1407.1838333766416, 1418.6735402398751, 1446.9059511131768, 1450.216882883243, 13052.543160989368, 13453.730925381487, 13584.388702508386, 13596.0, 13627.523036693718, 12656.557020504284, 12887.871121288785, 13414.26221557814, 13431.932840335785, 13448.0, 2674.6522952016257, 2725.9182224533297, 2742.684591272009, 2745.574435962419, 2749.4363655450684, 2591.9740802592014, 2718.4698983698186, 2721.4421043685998, 2722.68689100753, 2728.304282407983, 2260.5026894083276, 2344.402177444749, 2447.620618804079, 2685.8119931604797, 2725.059854350246, 2657.574788033915, 2659.0427446119384, 2663.6670416197976, 2671.7461841125064, 2673.625692403065, 2641.867906604662, 2654.0, 2657.694365147994, 2658.601209818543, 2726.332048648094, 13079.28063956482, 13111.737765244738, 13167.868321316855, 13216.140950838164, 13223.206607603575, 7260.564366138048, 7483.0, 7537.962310188469, 7538.773836784905, 7543.207963163899, 3880.2433525462548, 4482.372467854503, 4631.212693842055, 4735.952640473585, 4744.383230180055, 1390.4507719450862, 1396.190209678384, 1396.322783450026, 1420.2188796162172, 1427.1651084115804, 17638.647227055386, 18101.366452174243, 19512.024398780006, 19635.509112272164, 19653.606927861507, 5042.142835717937, 5217.947820521822, 6096.664683442454, 6450.580712253689, 6495.610263384117, 13907.0, 13958.511452099128, 13968.790468142986, 13980.720385592434, 14000.929995350161, 7333.376662983706, 7348.926510734825, 7357.153927298429, 7386.0, 7390.556566606021, 6777.525573209927, 6816.863662726718, 6872.10662613857, 6957.965210174018, 6963.512554121163, 4907.779149938261, 5048.545630893237, 5068.189089745623, 5085.8728531787, 5086.440491545927, 1393.770027945398, 1407.5917983784793, 1410.8659677330638, 1424.985750142506, 1447.7176950494552, 750.1785544828405, 754.8754455514888, 828.2669837194127, 838.4089217101929, 860.2902605350611, 6873.20958089816, 7102.970069339916, 7161.89257161143, 7240.674169662378, 7285.307895749896, 613.5367797313, 652.455199908082, 684.2678334182377, 716.5485743981362, 718.791550450374, 3661.6704496595435, 4111.198316328347, 4174.3947127666315, 4321.0709697415205, 4372.978135109368, 17297.65404691899, 17772.289108435776, 17926.372576960006, 17964.550886227687, 17996.920184788956, 17590.856594321584, 17684.823151768574, 17706.114694265234, 17795.822041779677, 17967.910160449115, 9293.163615274658, 9433.528323583794, 9463.574139163755, 9465.337426380225, 9478.241740660731, 6422.871542569216, 6633.209033561008, 7630.923690763021, 7771.533707977539, 8113.18868113184, 14721.852781472047, 15809.68380632404, 16109.677806443571, 16297.837021629632, 16459.34162633481, 16140.273687684317, 16454.84816062864, 16691.74962375541, 16812.907161034494, 17167.57081072983, 10035.54840032215, 10621.628243011457, 10622.415767132881, 10712.035916767529, 10720.195985300978, 12226.510939562319, 12302.446389912297, 12332.691682708004, 12365.75268494643, 12370.81443778362, 9461.773136673743, 9637.951810240904, 9954.950225248827, 10137.949310253402, 10163.898361016296, 9437.858432123661, 9450.196733277748, 9520.143187113194, 9807.656732014346, 10020.24848136378, 8590.398672092893, 8680.609372578376, 8697.304215662858, 8701.17338852808, 8740.08229135957, 6174.969125154345, 6180.0, 6244.937550624436, 6512.641804700787, 6528.347165283433, 6658.900116498159, 6672.4328432081875, 6878.484113691393, 6888.793336199721, 6988.336108069725], "value": 5877.4871484747655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.60935369307543, 431.43912913212813, 435.7776437093954, 444.10734423808145, 468.3723810094473, 5183.974080129598, 5222.764975576094, 5680.659160450371, 6538.248101468333, 6563.934360656392, 4765.618750499961, 5572.693501857396, 6125.418085281903, 6377.521685873567, 6393.968030159855, 4154.543000269968, 4911.901761964758, 4932.027189124361, 4951.0592987332475, 4983.975080124613, 4969.329140566014, 5016.623753218505, 5024.82413115541, 5026.623003274751, 5038.118329292359, 4246.363045543162, 4481.753503557303, 4906.950930490685, 4933.827316043939, 4964.354633897607, 4110.794460276974, 4553.2714765637675, 4924.778384972685, 4945.851624451254, 5007.824726134604, 4181.749095054306, 4196.790160491963, 4203.97898010511, 4215.3887686285625, 4220.957790422087, 6234.3453937336835, 6263.655498947556, 6265.154204182422, 6275.6548389838545, 6332.461740752042, 4717.622590192776, 5559.916601250984, 5705.286839145107, 5749.7700091996685, 5780.971095144498, 5530.336359636829, 5732.369439361667, 6073.878522429527, 6895.89665653493, 6919.723211071601, 4805.711657300538, 4807.711537307773, 4812.639052071108, 4819.95180048202, 4825.659555115766, 6933.37599616037, 6963.65181740916, 7244.927550724478, 7256.875184346382, 7391.408687305003, 4908.9754551227015, 4912.263160525949, 4917.918058027229, 4926.6551341406475, 4927.655064145475, 4774.904501910012, 4993.650444468853, 5007.874803129878, 5009.223570346602, 5009.499050095034, 5704.857378565486, 5709.714514274353, 5712.685802280831, 5719.369090218463, 5733.226014488074, 5550.91673624896, 5768.451997060272, 6986.790396288119, 7028.332308430691, 7029.859402811915, 1705.4713038957964, 1722.7415887616958, 1729.3428497170994, 1733.4106403822761, 1748.8163742807076, 908.418612088264, 1086.1582273737758, 1142.2232881640564, 1143.3597185576104, 1148.385613696685, 5205.0, 5850.0, 6207.193064901534, 6425.646589437626, 6429.0, 5952.0, 6816.556923799937, 7048.259932707095, 7057.4001209896705, 7093.290670932969, 6474.514411419163, 6570.901436478457, 6572.9247866323185, 6577.736890524421, 6577.967110164419, 3462.7576069674883, 3749.8750374887422, 3755.75587586806, 3791.33651610967, 3811.733178677521, 6310.242770867433, 7049.859002819915, 7096.7516136934955, 7228.855422891513, 7324.597147156851, 5309.495597918191, 6429.903551446732, 6500.6424646643945, 7356.77929662111, 7413.2957369049855, 6365.427111559889, 6430.64631445275, 6718.924972004455, 7230.927690723131, 7391.8152046199275, 6303.684815759286, 7173.605451700207, 7180.630391970742, 7183.712651493882, 7211.855762884608, 7020.929790702027, 7041.753538626123, 7088.64556772149, 7091.468139889632, 7100.4319654428555, 7333.779986600411, 7346.045014148127, 7346.96326518364, 7369.557826530426, 7413.888791668237, 4849.903001940012, 4858.975705121452, 4882.584980276716, 4900.166971614763, 4913.164761990399, 5979.4917432018765, 6004.979153543995, 6830.394857208453, 7093.432525398059, 7166.319199676022, 5960.582759206987, 6160.260768707604, 6740.79777606653, 6929.896051559332, 6969.0, 6265.310815810349, 6427.710753016032, 6888.862222755617, 6893.7931862044215, 6905.205901321412, 5671.773129074791, 6217.906731398942, 6777.559458635173, 6801.625910574768, 6845.452363810983, 6751.594904305757, 6841.0, 6923.515353925177, 6925.549839260432, 6928.480363972823, 6722.722682690273, 6726.461883049442, 6755.527113102035, 6756.797296081126, 6801.829954251183], "value": 5046.314036411659, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.7094104240388, 772.8763397856344, 822.6837060702874, 824.7649419915325, 864.5201912938321, 13861.0, 16796.664066718673, 16926.322947082102, 16953.745693814588, 17195.656086878254, 12769.93615031926, 13388.933055334734, 13407.128536645112, 13438.664033399169, 14018.719625607482, 643.6427782580678, 792.4492477727981, 928.0, 930.9674161404319, 931.4132096779019, 952.2382094324513, 960.3037797596721, 966.8888077871029, 972.9027097290285, 974.2692980264801, 1004.7337455574278, 1005.9446730429823, 1007.2244371833684, 1009.4548943570463, 1011.2163073617932, 668.1314291421157, 684.3737979748524, 692.5152393324693, 1007.0885848307294, 1009.3136667066392, 711.9252478489788, 731.3016069653521, 732.5092188233842, 736.5065406177873, 738.1326940844486, 14625.195614241213, 16218.513444596681, 16409.83590164095, 16543.255553500123, 16589.83410165895, 16354.0, 16865.0, 17329.393471228446, 17451.738223926524, 17610.383636572788, 14184.85815141856, 14318.64203394913, 14395.568132956028, 14458.49395271171, 14634.0, 3447.120984149051, 3452.067941655739, 3479.895603131885, 3480.269143479872, 3483.4078206704667, 94320.0567994325, 138416.0, 143059.28470357685, 143712.0, 143987.0, 11026.669199924016, 11158.386288754196, 13067.215967042008, 14023.859761402255, 14272.785908211385, 22653.660195097087, 25879.0942317018, 25929.870350648504, 25968.740312596634, 25972.870135649202, 7965.681372745025, 7987.560684162427, 8012.3590112790835, 8096.230858068474, 8112.918870811334, 3888.4556162137324, 3976.2842688315804, 3980.522337319482, 3985.322495175827, 3990.2618015667194, 3892.727509074338, 4616.0767846430845, 4620.330052142488, 4622.514635963243, 4634.907301854011, 985.6698006168014, 1031.8142734307896, 1036.7356324137297, 1038.231708535678, 1039.1478987230398, 631.4601016131141, 646.9741210351533, 648.5103746671233, 671.6037537852691, 678.3792829560897, 9870.802583948425, 11145.442727863736, 12157.148999570121, 12371.69070773238, 12459.866387476392, 616.8185201220041, 637.5250438423404, 670.8960111182774, 672.042339665974, 677.4986509982577, 5071.3153724247495, 5498.697571633518, 5515.4760297771645, 5615.9157612635845, 5840.027524719888, 140651.0, 144780.27609861884, 147560.52439475682, 149645.0, 152194.0, 104865.42701859478, 122520.0, 124772.0, 125320.0, 131097.0, 106505.0, 110151.0, 110740.446297768, 110797.99999999838, 115395.69208615948, 10667.199960002876, 11236.94381528087, 11855.17013809025, 11996.820047699117, 12765.553205638129, 41431.0, 43891.78054109709, 45601.77199114116, 46623.40878696266, 49978.25032624441, 63896.68051659712, 83891.98891793916, 85745.14254857371, 91336.543317283, 91728.0, 15887.443939462064, 16034.118123502853, 16150.03099814015, 16152.919235403748, 16181.433649822671, 17884.91057544704, 18085.0, 18445.81554184441, 18590.0, 19043.42869713911, 17393.956362618283, 17521.649567008335, 17728.556786079935, 18028.918264904147, 18232.17955192046, 16705.582360441087, 17406.564835879206, 17509.56226094307, 17604.91197544004, 17738.822611773718, 16730.4144354947, 16958.982461052376, 17027.574310641838, 17265.56836079108, 17578.296868125133, 11252.60615878473, 11842.76314473723, 12138.939305303416, 12380.133390662568, 12499.56251531192, 11912.821307680219, 12277.754444910874, 13185.736285274432, 13503.45986160582, 14381.0], "value": 8603.0424185308, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 114.0, 122.0, 120.0, 165.0, 168.0, 123.0, 119.0, 114.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 118.0, 146.0, 134.0, 110.0, 126.0, 109.0, 138.0, 141.0, 138.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 200.0, 216.0, 214.0, 220.0, 220.0, 198.0, 209.0, 196.0], "value": 206.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 75.0, 124.0, 60.0, 57.0, 70.0, 63.0, 128.0, 62.0], "value": 78.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 110.0, 71.0, 70.0, 83.0, 105.0, 74.0, 74.0, 115.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 133.0, 107.0, 137.0, 115.0, 91.0, 77.0, 83.0, 122.0, 136.0], "value": 109.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 129.0, 156.0, 98.0, 112.0, 97.0, 139.0, 154.0, 98.0, 97.0], "value": 117.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 137.0, 139.0, 116.0, 140.0, 147.0, 92.0, 92.0, 87.0, 92.0], "value": 113.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 52.0, 54.0, 54.0, 54.0, 53.0, 52.0, 52.0, 54.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 57.0, 64.0, 59.0, 60.0, 58.0, 55.0, 57.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 105.0, 83.0, 80.0, 81.0, 81.0, 79.0, 79.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 124.0, 119.0, 145.0, 161.0, 157.0, 154.0, 154.0, 140.0, 164.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 183.0, 177.0, 188.0, 215.0, 173.0, 228.0, 219.0, 176.0, 205.0], "value": 194.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 64.0, 66.0, 66.0, 63.0, 69.0, 65.0, 63.0], "value": 65.7, "unit": "score"}]}]} 10:07:11 INFO - Return code: 0 10:07:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:11 INFO - # TBPL SUCCESS # 10:07:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:11 INFO - 2017-06-11 10:07:11.272 screenresolution[1693:18521] starting screenresolution argv=screenresolution get 10:07:11 INFO - 2017-06-11 10:07:11.275 screenresolution[1693:18521] Display 0: 1600x1200x32@60 10:07:11 INFO - 2017-06-11 10:07:11.281 screenresolution[1694:18524] starting screenresolution argv=screenresolution list 10:07:11 INFO - Available Modes on Display 0 10:07:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:11 INFO - Intel Iris: 10:07:11 INFO - Chipset Model: Intel Iris 10:07:11 INFO - Type: GPU 10:07:11 INFO - Bus: Built-In 10:07:11 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:11 INFO - Vendor: Intel (0x8086) 10:07:11 INFO - Device ID: 0x0a2e 10:07:11 INFO - Revision ID: 0x0009 10:07:11 INFO - Displays: 10:07:11 INFO - Display: 10:07:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:11 INFO - Display Serial Number: 1600x1200 60 10:07:11 INFO - Main Display: Yes 10:07:11 INFO - Mirror: Off 10:07:11 INFO - Online: Yes 10:07:11 INFO - Rotation: Supported 10:07:11 INFO - Return code: 0 10:07:11 INFO - Running post-action listener: _resource_record_post_action 10:07:11 INFO - [mozharness: 2017-06-11 17:07:11.522162Z] Finished run-tests step (success) 10:07:11 INFO - Running post-run listener: _resource_record_post_run 10:07:11 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 34550784; Write bytes: 579195904; Read time: 220; Write time: 1774 10:07:11 INFO - TinderboxPrint: CPU usage
53.3% 10:07:11 INFO - TinderboxPrint: I/O read bytes / time
34,550,784 / 220 10:07:11 INFO - TinderboxPrint: I/O write bytes / time
579,195,904 / 1,774 10:07:11 INFO - TinderboxPrint: CPU idle
2,348.5 (46.7%) 10:07:11 INFO - TinderboxPrint: CPU system
1,028.1 (20.4%) 10:07:11 INFO - TinderboxPrint: CPU user
1,657.1 (32.9%) 10:07:11 INFO - TinderboxPrint: Swap in / out
283,049,984 / 0 10:07:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247994880; Write bytes: 240353280; Read time: 17863; Write time: 485 10:07:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:07:11 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 32388096; Write bytes: 319876096; Read time: 199; Write time: 1225 10:07:12 INFO - Running post-run listener: _upload_blobber_files 10:07:12 INFO - Blob upload gear active. 10:07:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:07:12 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:07:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:07:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:13 INFO - (blobuploader) - INFO - Done attempting. 10:07:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:07:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:07:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:14 INFO - (blobuploader) - INFO - Done attempting. 10:07:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:07:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:15 INFO - (blobuploader) - INFO - Done attempting. 10:07:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:17 INFO - (blobuploader) - INFO - Done attempting. 10:07:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:17 INFO - Return code: 0 10:07:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad0d08041e0b13e0e0e31b840a7c5933c226fbc304ff7456c9ca9ae9719f2a68a630fac5b1527606d0b0d86f160aaa6e7c0bf47d546895b2a5e74f8b4e24ed46", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8b6810ea30e69d95bc241e842b2206a0ec082f0e776ac994392dd1c0e36550c8955a01516616a7b4a95a6921f2b059bfc1f97385082a56b8ad0773a32f5c62b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d054c4e2e1215e9f5b574b4ffbc32b2e37726dcc52b58dfb353f23dc97d5c031e6208b282675f16b04de7074b2b715dca6ee6d3c3e30619d21cbb0417e152d09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/468e691c41987102738751c4482a07eb37479abf73f727f8aa778af20bc3431367e845ca979551b1e490a7028f817e2ea5f15f6c8996b8bf69337376fa3417a4"} 10:07:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:17 INFO - Contents: 10:07:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad0d08041e0b13e0e0e31b840a7c5933c226fbc304ff7456c9ca9ae9719f2a68a630fac5b1527606d0b0d86f160aaa6e7c0bf47d546895b2a5e74f8b4e24ed46", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8b6810ea30e69d95bc241e842b2206a0ec082f0e776ac994392dd1c0e36550c8955a01516616a7b4a95a6921f2b059bfc1f97385082a56b8ad0773a32f5c62b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d054c4e2e1215e9f5b574b4ffbc32b2e37726dcc52b58dfb353f23dc97d5c031e6208b282675f16b04de7074b2b715dca6ee6d3c3e30619d21cbb0417e152d09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/468e691c41987102738751c4482a07eb37479abf73f727f8aa778af20bc3431367e845ca979551b1e490a7028f817e2ea5f15f6c8996b8bf69337376fa3417a4"} 10:07:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:17 INFO - Copying logs to upload dir... 10:07:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.866427 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-11 10:07:18.072602) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-11 10:07:18.076980) ========= 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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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/ad0d08041e0b13e0e0e31b840a7c5933c226fbc304ff7456c9ca9ae9719f2a68a630fac5b1527606d0b0d86f160aaa6e7c0bf47d546895b2a5e74f8b4e24ed46", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8b6810ea30e69d95bc241e842b2206a0ec082f0e776ac994392dd1c0e36550c8955a01516616a7b4a95a6921f2b059bfc1f97385082a56b8ad0773a32f5c62b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d054c4e2e1215e9f5b574b4ffbc32b2e37726dcc52b58dfb353f23dc97d5c031e6208b282675f16b04de7074b2b715dca6ee6d3c3e30619d21cbb0417e152d09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/468e691c41987102738751c4482a07eb37479abf73f727f8aa778af20bc3431367e845ca979551b1e490a7028f817e2ea5f15f6c8996b8bf69337376fa3417a4"} build_url:https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011005 build_url: 'https://queue.taskcluster.net/v1/task/VoiAB_HTS2mKcMfz8zjcVg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad0d08041e0b13e0e0e31b840a7c5933c226fbc304ff7456c9ca9ae9719f2a68a630fac5b1527606d0b0d86f160aaa6e7c0bf47d546895b2a5e74f8b4e24ed46", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8b6810ea30e69d95bc241e842b2206a0ec082f0e776ac994392dd1c0e36550c8955a01516616a7b4a95a6921f2b059bfc1f97385082a56b8ad0773a32f5c62b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d054c4e2e1215e9f5b574b4ffbc32b2e37726dcc52b58dfb353f23dc97d5c031e6208b282675f16b04de7074b2b715dca6ee6d3c3e30619d21cbb0417e152d09", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/468e691c41987102738751c4482a07eb37479abf73f727f8aa778af20bc3431367e845ca979551b1e490a7028f817e2ea5f15f6c8996b8bf69337376fa3417a4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-11 10:07:18.121871) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 10:07:18.122241) ========= 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.TChSH8hQ1I/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.lwOuXHEaxF/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.005122 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-11 10:07:18.173712) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-11 10:07:18.174071) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-11 10:07:18.174398) ========= ========= Total master_lag: 0.29 =========