builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0276 starttime: 1494597656.62 results: success (0) revision: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.620545) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.621021) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.621337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.646714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.647071) ========= 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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-05-12 07:00:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-05-12 07:00:56 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135482 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.805611) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.805962) ========= 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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.026135 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:56.853789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 07:00:56.854144) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 030c0a7c878192d8dc1b04c182575c2f7858cdd0 --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 030c0a7c878192d8dc1b04c182575c2f7858cdd0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Vy89CkFRpa/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.HtRoYWkz8r/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-05-12 07:00:56,960 truncating revision to first 12 chars 2017-05-12 07:00:56,960 Setting DEBUG logging. 2017-05-12 07:00:56,960 attempt 1/10 2017-05-12 07:00:56,961 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/030c0a7c8781?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-12 07:00:57,673 unpacking tar archive at: mozilla-central-030c0a7c8781/testing/mozharness/ program finished with exit code 0 elapsedTime=0.993031 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 07:00:57.869009) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:57.869367) ========= script_repo_revision: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:57.869754) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:57.870052) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:00:57.889031) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-12 07:00:57.889415) ========= /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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:00:58 INFO - MultiFileLogger online at 20170512 07:00:58 in /builds/slave/test 07:00: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 07:00:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:00:58 INFO - {'append_to_log': False, 07:00:58 INFO - 'base_work_dir': '/builds/slave/test', 07:00:58 INFO - 'blob_upload_branch': 'Firefox', 07:00:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:00:58 INFO - 'branch': 'Firefox', 07:00:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:00:58 INFO - 'config_files': ('talos/mac_config.py',), 07:00:58 INFO - 'default_actions': ('clobber', 07:00:58 INFO - 'read-buildbot-config', 07:00:58 INFO - 'download-and-extract', 07:00:58 INFO - 'populate-webroot', 07:00:58 INFO - 'create-virtualenv', 07:00:58 INFO - 'install', 07:00:58 INFO - 'run-tests'), 07:00:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:00:58 INFO - 'download_minidump_stackwalk': True, 07:00:58 INFO - 'download_symbols': 'ondemand', 07:00:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:00:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:00:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:00:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:00:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:00:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:00:58 INFO - 'gecko_profile': False, 07:00:58 INFO - 'gecko_profile_interval': 0, 07:00:58 INFO - 'installer_path': 'installer.exe', 07:00:58 INFO - 'log_level': 'info', 07:00:58 INFO - 'log_name': 'talos', 07:00:58 INFO - 'log_to_console': True, 07:00:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:00:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:00:58 INFO - 'opt_config_files': (), 07:00:58 INFO - 'pip_index': False, 07:00:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:00:58 INFO - 'cmd': ('bash', 07:00:58 INFO - '-c', 07:00:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:00:58 INFO - 'enabled': True, 07:00:58 INFO - 'halt_on_failure': False, 07:00:58 INFO - 'name': 'check_screen_resolution'},), 07:00:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:00:58 INFO - 'cmd': ('bash', 07:00:58 INFO - '-c', 07:00:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:00:58 INFO - 'enabled': True, 07:00:58 INFO - 'halt_on_failure': False, 07:00:58 INFO - 'name': 'check_screen_resolution'},), 07:00:58 INFO - 'run_cmd_checks_enabled': True, 07:00:58 INFO - 'suite': 'dromaeojs-e10s', 07:00:58 INFO - 'system_bits': '32', 07:00:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:00:58 INFO - 'title': 't-yosemite-r7-0276', 07:00:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:00:58 INFO - 'use_talos_json': True, 07:00:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:00:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:00:58 INFO - 'work_dir': 'build'} 07:00:58 INFO - [mozharness: 2017-05-12 14:00:58.102959Z] Running clobber step. 07:00:58 INFO - Running pre-action listener: _resource_record_pre_action 07:00:58 INFO - Running main action method: clobber 07:00:58 INFO - rmtree: /builds/slave/test/build 07:00:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:00:58 INFO - Running post-action listener: _resource_record_post_action 07:00:58 INFO - [mozharness: 2017-05-12 14:00:58.108404Z] Finished clobber step (success) 07:00:58 INFO - [mozharness: 2017-05-12 14:00:58.108553Z] Running read-buildbot-config step. 07:00:58 INFO - Running pre-action listener: _resource_record_pre_action 07:00:58 INFO - Running main action method: read_buildbot_config 07:00:58 INFO - Using buildbot properties: 07:00:58 INFO - { 07:00:58 INFO - "project": "", 07:00:58 INFO - "product": "firefox", 07:00:58 INFO - "who": "cbook@mozilla.com", 07:00:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:00:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:00:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:00:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:00:58 INFO - "stage_platform": "macosx64", 07:00:58 INFO - "basedir": "/builds/slave/test", 07:00:58 INFO - "buildnumber": 61, 07:00:58 INFO - "platform": "yosemite_r7", 07:00:58 INFO - "parent_task_id": "RhC-X0LeQPWVeQaPIwCr_g", 07:00:58 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:00:58 INFO - "slavebuilddir": "test", 07:00:58 INFO - "taskId": "S2HAqm2NRu-NnyjKH8IFVw", 07:00:58 INFO - "branch": "mozilla-central", 07:00:58 INFO - "script_repo_revision": "production", 07:00:58 INFO - "revision": "030c0a7c878192d8dc1b04c182575c2f7858cdd0", 07:00:58 INFO - "slavename": "t-yosemite-r7-0276", 07:00:58 INFO - "repo_path": "mozilla-central" 07:00:58 INFO - } 07:00:58 INFO - Finding installer, test and symbols from parent task. 07:00:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2HAqm2NRu-NnyjKH8IFVw'}, attempt #1 07:00:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2HAqm2NRu-NnyjKH8IFVw'}, attempt #1 07:00:59 INFO - Task dependencies: M2ZmG5JhReGt6parYpoB8Q 07:00:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g'}, attempt #1 07:00:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:00:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/test_packages.json 07:00:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/target.crashreporter-symbols.zip 07:00:59 INFO - Running post-action listener: _resource_record_post_action 07:00:59 INFO - [mozharness: 2017-05-12 14:00:59.853952Z] Finished read-buildbot-config step (success) 07:00:59 INFO - [mozharness: 2017-05-12 14:00:59.854070Z] Running download-and-extract step. 07:00:59 INFO - Running pre-action listener: _resource_record_pre_action 07:00:59 INFO - Running main action method: download_and_extract 07:00:59 INFO - mkdir: /builds/slave/test/build/tests 07:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:00:59 INFO - trying https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:00:59 INFO - Downloading https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/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 07:00:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/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 07:01:00 INFO - Downloaded 1325 bytes. 07:01:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:01:00 INFO - Using the following test package requirements: 07:01:00 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:01:00 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:01:00 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:01:00 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:01:00 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'jsshell-mac.zip'], 07:01:00 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:01:00 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:01:00 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:01:00 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:01:00 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:01:00 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:01:00 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:01:00 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:01:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:01:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:01:00 INFO - Fetch https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:01:02 INFO - Content-Length response header: 56563765 07:01:02 INFO - Bytes received: 56563765 07:01:19 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 07:01:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:01:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:01:21 INFO - Content-Length response header: 56563765 07:01:21 INFO - Bytes received: 56563765 07:01:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:01:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:01:38 INFO - Fetch https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:01:39 INFO - Content-Length response header: 14409411 07:01:39 INFO - Bytes received: 14409411 07:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:40 INFO - trying https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:01:40 INFO - Downloading https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/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 07:01:42 INFO - Downloaded 75933392 bytes. 07:01:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:01:42 INFO - mkdir: /builds/slave/test/properties 07:01:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:01:42 INFO - Writing to file /builds/slave/test/properties/build_url 07:01:42 INFO - Contents: 07:01:42 INFO - build_url:https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:01:42 INFO - Running post-action listener: _resource_record_post_action 07:01:42 INFO - Running post-action listener: set_extra_try_arguments 07:01:42 INFO - [mozharness: 2017-05-12 14:01:42.289357Z] Finished download-and-extract step (success) 07:01:42 INFO - [mozharness: 2017-05-12 14:01:42.289496Z] Running populate-webroot step. 07:01:42 INFO - Running pre-action listener: _resource_record_pre_action 07:01:42 INFO - Running main action method: populate_webroot 07:01:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:01:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:01:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:01:42 INFO - '--tppagecycles', 07:01:42 INFO - '7'], 07:01:42 INFO - 'tests': ['tsvgm']}}, 07:01:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:01:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:01:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:01:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:01:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:01:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:01:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['damp', 'tps']}, 07:01:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'tests': ['damp', 'tps']}, 07:01:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['dromaeo_dom']}, 07:01:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:01:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:01:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:01:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['a11yr', 07:01:42 INFO - 'ts_paint', 07:01:42 INFO - 'tpaint', 07:01:42 INFO - 'sessionrestore', 07:01:42 INFO - 'sessionrestore_no_auto_restore', 07:01:42 INFO - 'tabpaint']}, 07:01:42 INFO - 'other-e10s': {'tests': ['a11yr', 07:01:42 INFO - 'ts_paint', 07:01:42 INFO - 'tpaint', 07:01:42 INFO - 'sessionrestore', 07:01:42 INFO - 'sessionrestore_no_auto_restore', 07:01:42 INFO - 'tabpaint']}, 07:01:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:01:42 INFO - 'ts_paint', 07:01:42 INFO - 'tpaint', 07:01:42 INFO - 'sessionrestore', 07:01:42 INFO - 'sessionrestore_no_auto_restore', 07:01:42 INFO - 'tabpaint']}, 07:01:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:01:42 INFO - 'ts_paint', 07:01:42 INFO - 'tpaint', 07:01:42 INFO - 'sessionrestore', 07:01:42 INFO - 'sessionrestore_no_auto_restore', 07:01:42 INFO - 'tabpaint']}, 07:01:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['a11yr', 07:01:42 INFO - 'ts_paint', 07:01:42 INFO - 'tpaint', 07:01:42 INFO - 'sessionrestore', 07:01:42 INFO - 'sessionrestore_no_auto_restore', 07:01:42 INFO - 'tabpaint']}, 07:01:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['a11yr', 07:01:42 INFO - 'ts_paint', 07:01:42 INFO - 'tpaint', 07:01:42 INFO - 'sessionrestore', 07:01:42 INFO - 'sessionrestore_no_auto_restore', 07:01:42 INFO - 'tabpaint']}, 07:01:42 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:01:42 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:01:42 INFO - 'bloom_basic_ref']}, 07:01:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['tsvgx', 07:01:42 INFO - 'tsvgr_opacity', 07:01:42 INFO - 'tart', 07:01:42 INFO - 'tscrollx', 07:01:42 INFO - 'cart', 07:01:42 INFO - 'tsvg_static']}, 07:01:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:01:42 INFO - 'tsvgr_opacity', 07:01:42 INFO - 'tart', 07:01:42 INFO - 'tscrollx', 07:01:42 INFO - 'cart', 07:01:42 INFO - 'tsvg_static']}, 07:01:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'talos_options': ['--disable-e10s'], 07:01:42 INFO - 'tests': ['tp5o']}, 07:01:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:01:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'talos_options': ['--disable-e10s', 07:01:42 INFO - '--xperf_path', 07:01:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:01:42 INFO - 'tests': ['tp5n']}, 07:01:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:01:42 INFO - 'talos_options': ['--xperf_path', 07:01:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:01:42 INFO - 'tests': ['tp5n']}}, 07:01:42 INFO - 'talos.zip': {'path': '', 07:01:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:01:42 INFO - Running post-action listener: _resource_record_post_action 07:01:42 INFO - [mozharness: 2017-05-12 14:01:42.309769Z] Finished populate-webroot step (success) 07:01:42 INFO - [mozharness: 2017-05-12 14:01:42.309882Z] Running create-virtualenv step. 07:01:42 INFO - Running pre-action listener: _resource_record_pre_action 07:01:42 INFO - Running main action method: create_virtualenv 07:01:42 INFO - Creating virtualenv /builds/slave/test/build/venv 07:01:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:01:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:01:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:01:42 INFO - Using real prefix '/tools/python27' 07:01:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:01:43 INFO - Installing distribute.............................................................................................................................................................................................done. 07:01:46 INFO - Installing pip.................done. 07:01:46 INFO - Return code: 0 07:01:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:01:46 INFO - Reading from file tmpfile_stdout 07:01:46 INFO - Output received: 07:01:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:01:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:46 INFO - 'HOME': '/Users/cltbld', 07:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:46 INFO - 'LOGNAME': 'cltbld', 07:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:46 INFO - 'MOZ_NO_REMOTE': '1', 07:01:46 INFO - 'NO_EM_RESTART': '1', 07:01:46 INFO - 'PAGER': '/bin/cat', 07:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:46 INFO - 'PWD': '/builds/slave/test', 07:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:46 INFO - 'SHELL': '/bin/bash', 07:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:46 INFO - 'USER': 'cltbld', 07:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:46 INFO - 'XPC_FLAGS': '0x0', 07:01:46 INFO - 'XPC_SERVICE_NAME': '0', 07:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:46 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 07:01:46 INFO - Cleaning up... 07:01:46 INFO - Return code: 0 07:01:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:46 INFO - 'HOME': '/Users/cltbld', 07:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:46 INFO - 'LOGNAME': 'cltbld', 07:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:46 INFO - 'MOZ_NO_REMOTE': '1', 07:01:46 INFO - 'NO_EM_RESTART': '1', 07:01:46 INFO - 'PAGER': '/bin/cat', 07:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:46 INFO - 'PWD': '/builds/slave/test', 07:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:46 INFO - 'SHELL': '/bin/bash', 07:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:46 INFO - 'USER': 'cltbld', 07:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:46 INFO - 'XPC_FLAGS': '0x0', 07:01:46 INFO - 'XPC_SERVICE_NAME': '0', 07:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:46 INFO - Downloading/unpacking psutil>=3.1.1 07:01:46 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 07:01:46 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 07:01:46 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 07:01:46 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 07:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:01:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:01:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:01:48 INFO - Installing collected packages: psutil 07:01:48 INFO - Running setup.py install for psutil 07:01:48 INFO - building 'psutil._psutil_osx' extension 07:01:48 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 07:01:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:01:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:01:49 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:01:49 INFO - building 'psutil._psutil_posix' extension 07:01:49 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 07:01:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:01:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:01:49 INFO - ^ 07:01:49 INFO - 1 warning generated. 07:01:49 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 07:01:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:01:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:01:49 INFO - Successfully installed psutil 07:01:49 INFO - Cleaning up... 07:01:49 INFO - Return code: 0 07:01:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:01: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')]} 07:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01: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')]} 07:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:01: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 mozsystemmonitor==0.3 07:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:49 INFO - 'HOME': '/Users/cltbld', 07:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:49 INFO - 'LOGNAME': 'cltbld', 07:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:49 INFO - 'MOZ_NO_REMOTE': '1', 07:01:49 INFO - 'NO_EM_RESTART': '1', 07:01:49 INFO - 'PAGER': '/bin/cat', 07:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:49 INFO - 'PWD': '/builds/slave/test', 07:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:49 INFO - 'SHELL': '/bin/bash', 07:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:49 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:49 INFO - 'USER': 'cltbld', 07:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:49 INFO - 'XPC_FLAGS': '0x0', 07:01:49 INFO - 'XPC_SERVICE_NAME': '0', 07:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:01: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 07:01: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 07:01: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 07:01: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 07:01:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:01:51 INFO - Installing collected packages: mozsystemmonitor 07:01:51 INFO - Running setup.py install for mozsystemmonitor 07:01:51 INFO - Successfully installed mozsystemmonitor 07:01:51 INFO - Cleaning up... 07:01:51 INFO - Return code: 0 07:01:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:51 INFO - 'HOME': '/Users/cltbld', 07:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:51 INFO - 'LOGNAME': 'cltbld', 07:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:51 INFO - 'MOZ_NO_REMOTE': '1', 07:01:51 INFO - 'NO_EM_RESTART': '1', 07:01:51 INFO - 'PAGER': '/bin/cat', 07:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:51 INFO - 'PWD': '/builds/slave/test', 07:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:51 INFO - 'SHELL': '/bin/bash', 07:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:51 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:51 INFO - 'USER': 'cltbld', 07:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:51 INFO - 'XPC_FLAGS': '0x0', 07:01:51 INFO - 'XPC_SERVICE_NAME': '0', 07:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:52 INFO - Downloading/unpacking jsonschema==2.5.1 07:01:52 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 07:01:52 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 07:01:52 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 07:01:52 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 07:01:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:01:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:01:53 INFO - warning: no files found matching '*.txt' 07:01:53 INFO - no previously-included directories found matching 'build' 07:01:53 INFO - no previously-included directories found matching 'dist' 07:01:53 INFO - no previously-included directories found matching '.git*' 07:01:53 INFO - Installing collected packages: jsonschema, functools32 07:01:53 INFO - Running setup.py install for functools32 07:01:53 INFO - warning: no files found matching '*.txt' 07:01:53 INFO - no previously-included directories found matching 'build' 07:01:53 INFO - no previously-included directories found matching 'dist' 07:01:53 INFO - no previously-included directories found matching '.git*' 07:01:54 INFO - Successfully installed jsonschema functools32 07:01:54 INFO - Cleaning up... 07:01:54 INFO - Return code: 0 07:01:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:01:54 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')]} 07:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:54 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')]} 07:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:54 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 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01:54 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 07:01:54 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 07:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:54 INFO - 'HOME': '/Users/cltbld', 07:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:54 INFO - 'LOGNAME': 'cltbld', 07:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:54 INFO - 'MOZ_NO_REMOTE': '1', 07:01:54 INFO - 'NO_EM_RESTART': '1', 07:01:54 INFO - 'PAGER': '/bin/cat', 07:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:54 INFO - 'PWD': '/builds/slave/test', 07:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:54 INFO - 'SHELL': '/bin/bash', 07:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:54 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:54 INFO - 'USER': 'cltbld', 07:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:54 INFO - 'XPC_FLAGS': '0x0', 07:01:54 INFO - 'XPC_SERVICE_NAME': '0', 07:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:01:55 INFO - Cleaning up... 07:01:55 INFO - Return code: 0 07:01:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:01: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')]} 07:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01: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')]} 07:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:55 INFO - 'HOME': '/Users/cltbld', 07:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:55 INFO - 'LOGNAME': 'cltbld', 07:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:55 INFO - 'MOZ_NO_REMOTE': '1', 07:01:55 INFO - 'NO_EM_RESTART': '1', 07:01:55 INFO - 'PAGER': '/bin/cat', 07:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:55 INFO - 'PWD': '/builds/slave/test', 07:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:55 INFO - 'SHELL': '/bin/bash', 07:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:55 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:55 INFO - 'USER': 'cltbld', 07:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:55 INFO - 'XPC_FLAGS': '0x0', 07:01:55 INFO - 'XPC_SERVICE_NAME': '0', 07:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:55 INFO - Downloading/unpacking blobuploader==1.2.4 07:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:01:57 INFO - Downloading blobuploader-1.2.4.tar.gz 07:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:01:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:01: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 07:01: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 07:01: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 07:01: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 07:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:01:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:01: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 07:01: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 07:01: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 07:01: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 07:01:58 INFO - Downloading docopt-0.6.1.tar.gz 07:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:01:58 INFO - Installing collected packages: blobuploader, requests, docopt 07:01:58 INFO - Running setup.py install for blobuploader 07:01:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:01:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:01:58 INFO - Running setup.py install for requests 07:01:58 INFO - Running setup.py install for docopt 07:01:59 INFO - Successfully installed blobuploader requests docopt 07:01:59 INFO - Cleaning up... 07:01:59 INFO - Return code: 0 07:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:01:59 INFO - 'HOME': '/Users/cltbld', 07:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:01:59 INFO - 'LOGNAME': 'cltbld', 07:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:59 INFO - 'MOZ_NO_REMOTE': '1', 07:01:59 INFO - 'NO_EM_RESTART': '1', 07:01:59 INFO - 'PAGER': '/bin/cat', 07:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:59 INFO - 'PWD': '/builds/slave/test', 07:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:01:59 INFO - 'SHELL': '/bin/bash', 07:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:01:59 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:01:59 INFO - 'USER': 'cltbld', 07:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:59 INFO - 'XPC_FLAGS': '0x0', 07:01:59 INFO - 'XPC_SERVICE_NAME': '0', 07:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:01:59 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-3lLnti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:01:59 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-1yVe_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:01:59 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-2dUrpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:01:59 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-aNg59c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:01:59 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Az7o2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:01:59 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-oejWV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-78U2Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KfjPnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iG7zB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6MusOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-LPctUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-OM0tc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-1VX610-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-4eA8GG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:00 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-eCdhHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:01 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-twRls0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:01 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-rg2HmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:02:01 INFO - Running setup.py install for manifestparser 07:02:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:02:01 INFO - Running setup.py install for mozcrash 07:02:01 INFO - Running setup.py install for mozdebug 07:02:01 INFO - Running setup.py install for mozdevice 07:02:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:02:01 INFO - Running setup.py install for mozfile 07:02:01 INFO - Running setup.py install for mozhttpd 07:02:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Running setup.py install for mozinfo 07:02:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Running setup.py install for mozInstall 07:02:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Running setup.py install for mozleak 07:02:02 INFO - Running setup.py install for mozlog 07:02:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Running setup.py install for moznetwork 07:02:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:02:02 INFO - Running setup.py install for mozprocess 07:02:02 INFO - Running setup.py install for mozprofile 07:02:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:02:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:02:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:02:03 INFO - Running setup.py install for mozrunner 07:02:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:02:03 INFO - Running setup.py install for mozscreenshot 07:02:03 INFO - Running setup.py install for moztest 07:02:03 INFO - Running setup.py install for mozversion 07:02:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:02:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:02:03 INFO - Cleaning up... 07:02:03 INFO - Return code: 0 07:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:03 INFO - 'HOME': '/Users/cltbld', 07:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:03 INFO - 'LOGNAME': 'cltbld', 07:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:03 INFO - 'MOZ_NO_REMOTE': '1', 07:02:03 INFO - 'NO_EM_RESTART': '1', 07:02:03 INFO - 'PAGER': '/bin/cat', 07:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:03 INFO - 'PWD': '/builds/slave/test', 07:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:03 INFO - 'SHELL': '/bin/bash', 07:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:02:03 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:03 INFO - 'USER': 'cltbld', 07:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:03 INFO - 'XPC_FLAGS': '0x0', 07:02:03 INFO - 'XPC_SERVICE_NAME': '0', 07:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-5prYUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-r0SJFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Nu15z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-S76JN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-2HGPdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-7R8KNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-GI0vdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-5HW_2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-8HMOjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:04 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)) 07:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:04 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-uzdWRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-vQRZvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-hGwnVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-PRKK0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-MD2b4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-3Xo0TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-FZsGTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:05 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ShkLHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:02:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:07 INFO - Downloading blessings-1.6.tar.gz 07:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:02:07 INFO - Installing collected packages: blessings 07:02:07 INFO - Running setup.py install for blessings 07:02:07 INFO - Successfully installed blessings 07:02:07 INFO - Cleaning up... 07:02:07 INFO - Return code: 0 07:02:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:02:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:02:07 INFO - Reading from file tmpfile_stdout 07:02:07 INFO - Current package versions: 07:02:07 INFO - blessings == 1.6 07:02:07 INFO - blobuploader == 1.2.4 07:02:07 INFO - docopt == 0.6.1 07:02:07 INFO - functools32 == 3.2.3-2 07:02:07 INFO - jsonschema == 2.5.1 07:02:07 INFO - manifestparser == 1.1 07:02:07 INFO - mozInstall == 1.12 07:02:07 INFO - mozcrash == 1.0 07:02:07 INFO - mozdebug == 0.1 07:02:07 INFO - mozdevice == 0.50 07:02:07 INFO - mozfile == 1.2 07:02:07 INFO - mozhttpd == 0.7 07:02:07 INFO - mozinfo == 0.9 07:02:07 INFO - mozleak == 0.1 07:02:07 INFO - mozlog == 3.4 07:02:07 INFO - moznetwork == 0.27 07:02:07 INFO - mozprocess == 0.25 07:02:07 INFO - mozprofile == 0.28 07:02:07 INFO - mozrunner == 6.13 07:02:07 INFO - mozscreenshot == 0.1 07:02:07 INFO - mozsystemmonitor == 0.3 07:02:07 INFO - moztest == 0.8 07:02:07 INFO - mozversion == 1.4 07:02:07 INFO - psutil == 3.1.1 07:02:07 INFO - requests == 1.2.3 07:02:07 INFO - wsgiref == 0.1.2 07:02:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:02:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:02:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:02:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:07 INFO - 'HOME': '/Users/cltbld', 07:02:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:07 INFO - 'LOGNAME': 'cltbld', 07:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:07 INFO - 'MOZ_NO_REMOTE': '1', 07:02:07 INFO - 'NO_EM_RESTART': '1', 07:02:07 INFO - 'PAGER': '/bin/cat', 07:02:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:07 INFO - 'PWD': '/builds/slave/test', 07:02:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:07 INFO - 'SHELL': '/bin/bash', 07:02:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:02:07 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:02:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:07 INFO - 'USER': 'cltbld', 07:02:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:07 INFO - 'XPC_FLAGS': '0x0', 07:02:07 INFO - 'XPC_SERVICE_NAME': '0', 07:02:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:02:08 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)) 07:02:08 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)) 07:02:08 INFO - Cleaning up... 07:02:08 INFO - Return code: 0 07:02:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:08 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 0x1070c5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070c8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5a957af40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, '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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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 07:02:08 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 07:02:08 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 07:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:08 INFO - 'HOME': '/Users/cltbld', 07:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:08 INFO - 'LOGNAME': 'cltbld', 07:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:08 INFO - 'MOZ_NO_REMOTE': '1', 07:02:08 INFO - 'NO_EM_RESTART': '1', 07:02:08 INFO - 'PAGER': '/bin/cat', 07:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:08 INFO - 'PWD': '/builds/slave/test', 07:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:08 INFO - 'SHELL': '/bin/bash', 07:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:02:08 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:08 INFO - 'USER': 'cltbld', 07:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:08 INFO - 'XPC_FLAGS': '0x0', 07:02:08 INFO - 'XPC_SERVICE_NAME': '0', 07:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:02:08 INFO - Cleaning up... 07:02:08 INFO - Return code: 0 07:02:08 INFO - Running post-action listener: _resource_record_post_action 07:02:08 INFO - Running post-action listener: _start_resource_monitoring 07:02:08 INFO - Starting resource monitoring. 07:02:08 INFO - [mozharness: 2017-05-12 14:02:08.383343Z] Finished create-virtualenv step (success) 07:02:08 INFO - [mozharness: 2017-05-12 14:02:08.384258Z] Running install step. 07:02:08 INFO - Running pre-action listener: _resource_record_pre_action 07:02:08 INFO - Running main action method: install 07:02:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:02:08 INFO - Reading from file tmpfile_stdout 07:02:08 INFO - Detecting whether we're running mozinstall >=1.0... 07:02:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:02:08 INFO - Reading from file tmpfile_stdout 07:02:08 INFO - Output received: 07:02:08 INFO - Usage: mozinstall [options] installer 07:02:08 INFO - Options: 07:02:08 INFO - -h, --help show this help message and exit 07:02:08 INFO - -d DEST, --destination=DEST 07:02:08 INFO - Directory to install application into. [default: 07:02:08 INFO - "/builds/slave/test"] 07:02:08 INFO - --app=APP Application being installed. [default: firefox] 07:02:08 INFO - mkdir: /builds/slave/test/build/application 07:02:08 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'] 07:02:08 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 07:02:29 INFO - Reading from file tmpfile_stdout 07:02:29 INFO - Output received: 07:02:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:02:29 INFO - Running post-action listener: _resource_record_post_action 07:02:29 INFO - [mozharness: 2017-05-12 14:02:29.525095Z] Finished install step (success) 07:02:29 INFO - [mozharness: 2017-05-12 14:02:29.525256Z] Running run-tests step. 07:02:29 INFO - Running pre-action listener: _resource_record_pre_action 07:02:29 INFO - Running main action method: run_tests 07:02:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:02:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:02:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:02:29 INFO - 2017-05-12 07:02:29.540 screenresolution[1628:7738] starting screenresolution argv=screenresolution get 07:02:29 INFO - 2017-05-12 07:02:29.542 screenresolution[1628:7738] Display 0: 1600x1200x32@60 07:02:29 INFO - 2017-05-12 07:02:29.548 screenresolution[1629:7740] starting screenresolution argv=screenresolution list 07:02:29 INFO - Available Modes on Display 0 07:02:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:02:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:02:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:02:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:02:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:02:29 INFO - Intel Iris: 07:02:29 INFO - Chipset Model: Intel Iris 07:02:29 INFO - Type: GPU 07:02:29 INFO - Bus: Built-In 07:02:29 INFO - VRAM (Dynamic, Max): 1536 MB 07:02:29 INFO - Vendor: Intel (0x8086) 07:02:29 INFO - Device ID: 0x0a2e 07:02:29 INFO - Revision ID: 0x0009 07:02:29 INFO - Displays: 07:02:29 INFO - Display: 07:02:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:02:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:02:29 INFO - Display Serial Number: 1600x1200 60 07:02:29 INFO - Main Display: Yes 07:02:29 INFO - Mirror: Off 07:02:29 INFO - Online: Yes 07:02:29 INFO - Rotation: Supported 07:02:29 INFO - Return code: 0 07:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:02:29 INFO - Python 2.7.3 07:02:29 INFO - Return code: 0 07:02:29 INFO - grabbing minidump binary from tooltool 07:02:29 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')]} 07:02:29 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 0x106dd6d30>, '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 0x106d89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106dcabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:02:29 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 07:02:29 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 07:02:29 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 07:02:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:02:29 INFO - Return code: 0 07:02:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:02:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:02:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:02:29 INFO - ENV: RUST_BACKTRACE is now 1 07:02:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:02:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:02:29 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/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 07:02:29 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/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0276 --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 07:02:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Vy89CkFRpa/Render', 07:02:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:29 INFO - 'HOME': '/Users/cltbld', 07:02:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:29 INFO - 'LOGNAME': 'cltbld', 07:02:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:02:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:29 INFO - 'MOZ_NO_REMOTE': '1', 07:02:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:02:29 INFO - 'NO_EM_RESTART': '1', 07:02:29 INFO - 'PAGER': '/bin/cat', 07:02:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:29 INFO - 'PWD': '/builds/slave/test', 07:02:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:02:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:29 INFO - 'RUST_BACKTRACE': '1', 07:02:29 INFO - 'SHELL': '/bin/bash', 07:02:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HtRoYWkz8r/Listeners', 07:02:29 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 07:02:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:29 INFO - 'USER': 'cltbld', 07:02:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:29 INFO - 'XPC_FLAGS': '0x0', 07:02:29 INFO - 'XPC_SERVICE_NAME': '0', 07:02:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:29 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/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--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 07:02:30 INFO - mozversion application_buildid: 20170512054043 07:02:30 INFO - mozversion application_changeset: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 07:02:30 INFO - mozversion application_display_name: Nightly 07:02:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:02:30 INFO - mozversion application_name: Firefox 07:02:30 INFO - mozversion application_remotingname: firefox 07:02:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:02:30 INFO - mozversion application_vendor: Mozilla 07:02:30 INFO - mozversion application_version: 55.0a1 07:02:30 INFO - mozversion platform_buildid: 20170512054043 07:02:30 INFO - mozversion platform_changeset: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 07:02:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:02:30 INFO - mozversion platform_version: 55.0a1 07:02:30 INFO - using testdate: 1494597750 07:02:30 INFO - actual date: 1494597750 07:02:30 INFO - starting webserver on 'localhost:49225' 07:02:30 INFO - SUITE-START | Running 2 tests 07:02:30 INFO - TEST-START | dromaeo_css 07:02:30 INFO - operating with platform_type : mac_ 07:02:30 INFO - Initialising browser for dromaeo_css test... 07:02:30 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpom0Rbv/profile http://localhost:49225/getInfo.html) 07:02:32 INFO - PID 1639 | 2017-05-12 07:02:32.722 plugin-container[1641:7921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 07:02:32 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:32 INFO - PID 1639 | 2017-05-12 07:02:32.725 plugin-container[1641:7921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x991b, name = 'com.apple.CFPasteboardClient' 07:02:32 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:32 INFO - PID 1639 | 2017-05-12 07:02:32.725 plugin-container[1641:7921] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:02:32 INFO - PID 1639 | __metrics Screen width/height:1600/1200 07:02:32 INFO - PID 1639 | colorDepth:24 07:02:32 INFO - PID 1639 | Browser inner width/height: 1024/691 07:02:32 INFO - PID 1639 | __metrics 07:02:33 INFO - PID 1639 | 2017-05-12 07:02:33.204 plugin-container[1642:7979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 07:02:33 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:33 INFO - PID 1639 | 2017-05-12 07:02:33.205 plugin-container[1642:7979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 07:02:33 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:33 INFO - PID 1639 | 2017-05-12 07:02:33.205 plugin-container[1642:7979] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:02:33 INFO - TEST-INFO | 1639: exit 0 07:02:33 INFO - Browser initialized. 07:02:33 INFO - Running cycle 1/1 for dromaeo_css test... 07:02:33 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpom0Rbv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:02:36 INFO - PID 1643 | 2017-05-12 07:02:36.175 plugin-container[1644:8087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 07:02:36 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:36 INFO - PID 1643 | 2017-05-12 07:02:36.176 plugin-container[1644:8087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 07:02:36 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:36 INFO - PID 1643 | 2017-05-12 07:02:36.176 plugin-container[1644:8087] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:02:36 INFO - PID 1643 | 2017-05-12 07:02:36.616 plugin-container[1645:8128] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954f, name = 'com.apple.tsm.portname' 07:02:36 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:36 INFO - PID 1643 | 2017-05-12 07:02:36.616 plugin-container[1645:8128] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 07:02:36 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:02:36 INFO - PID 1643 | 2017-05-12 07:02:36.616 plugin-container[1645:8128] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:02:46 INFO - PID 1643 | RSS: Main: 228339712 07:02:46 INFO - PID 1643 | 07:05:49 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 07:05:51 INFO - PID 1643 | RSS: Main: 253374464 07:05:51 INFO - PID 1643 | 07:08:53 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 07:08:53 INFO - PID 1643 | RSS: Main: 261607424 07:08:53 INFO - PID 1643 | 07:11:55 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 07:11:56 INFO - PID 1643 | RSS: Main: 271245312 07:11:56 INFO - PID 1643 | 07:14:57 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 07:14:58 INFO - PID 1643 | RSS: Main: 264036352 07:14:58 INFO - PID 1643 | 07:18:01 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 07:18:02 INFO - PID 1643 | RSS: Main: 267505664 07:18:02 INFO - PID 1643 | 07:21:03 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 07:21:04 INFO - PID 1643 | RSS: Main: 266645504 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | __start_tp_report 07:21:04 INFO - PID 1643 | _x_x_mozilla_page_load 07:21:04 INFO - PID 1643 | _x_x_mozilla_page_load_details 07:21:04 INFO - PID 1643 | |i|pagename|runs| 07:21:04 INFO - PID 1643 | |0;dojo.html;3027.8191505312925;3219.758518111142;3526.277113191796;3540.433530635099;3588.838502267398;4597.89650483884;6087.452129308362;6125;6134.049222370534;6181.938180618193;6105.399096011525;6125.989211780062;6131.376666650039;6141.907871381933;6186.226721659793;3999.7600143991453;4909.950900491003;5139.794408223685;5358.9282143571445;5501.14732216507;5390.8382748517415;5456.208849716789;5515.862103447426;5521.978433989705;5522.171674248874;5438.510534051954;5479.260299859528;5487.890242195173;5533.750981205835;5543.029969755296;5317.601179911522;5422.83731488052;5434.83695489136;5441.238226648273;5450.645708028966;5389.407165211824;5393.2179833923865;5394.838154855361;5462.5083742462975;5486.91769623456;5937.970310148465;6157.78447754331;6285.4343109119945;6293.653849038301;6298.244210694746;6572.309907459744;6646.169228846395;6652.501062420338;6660.23407308156;6670.866582668368;6456.192975878015;6498.935010649928;6664.300248473889;6719.126513553256;6719.2272888617945;6316.652584107918;6329.651869147195;6336.714847831859;6339.936600634027;6367.554271201064;6774.525783195228;6813.557118787263;6817.454603631797;6869.278725733826;6892.345227203407;6306.936930630727;6375.968120159462;6421.068945003042;6424.1327420798525;6480.51396145291;6636.800895973129;6662.433693136138;6682.431993280626;6693.598384096969;6702.631355275506;6435.195600549931;6569.572977756526;6579.078928949855;6592.208934927839;6603.075569420285;6328.6835658216905;6418.358164183544;6509.934900651027;6607.207135143717;6624.238212605515;5105.591552675852;5261.316028916216;5274.340707411574;5280.841574752687;5297;4628.67599268048;4667.253239481666;4697.647676424282;4708.529147085333;4727.952720472751;6208.379162083846;6274.811755647338;6325.9051114233325;6365.809025729235;6367.681615919279;6570.605763654196;6614.140161778938;6656.167979002625;6694.09629699994;6751.291114433012;6344.365563443619;6357.014662727192;6370.1718776558755;6375.979843225062;6391.9680401598625;5845.210896528999;5866.501347385435;5872.911906321408;5880.617759845596;5884.352721200665;6654.134962455044;6760.019797129487;6782.660866956534;6787.117674702158;6936.4797640176175;6664.300248473841;6701.8324541884895;6702.631355275506;6725.327467253235;6726.932730672631;6520;6569.967150164219;6773.93226067733;6789.592624442549;6867;6584.572002819993;6602.075709400784;6622.503312251504;6627.867442651023;6642.302558231316;6487.286398496256;6560.212774467094;6572.14562106923;6700;6740.090087838079;5403.891922161613;6348.428641422294;6398.072279519351;6467.579607325697;6527.281998980014;5253.632245742914;6356.77751278703;6482.448991835747;6482.935170648233;6492.772752953624;6234.844128896814;6378.457831084224;6382.393672601095;6382.680865956777;6418.390252925965;6304.621722696653;6315.147455093504;6451.161349024785;6473.741050357933;6479.870402592016;6348.269948955744;6378.96810515963;6383.329750376143;6383.712732926937;6388.329225431448 07:21:04 INFO - PID 1643 | |1;ext.html;687.3298533929419;784.0787075186656;818.2676504528446;1202.741410596721;1265.8164566137916;40047.79976100119;41148.58851411485;41206;41209.793951030275;41220.58779412209;41331.586684133224;41750;41875.79062104693;41892.371614425734;41970.79014604923;7879.999999999985;8011.479253848511;8398.874016889751;8572.14278572141;8591.871121933176;8484.915150848505;8586.570671466432;8595.312375010015;8598.87101693472;8599.44103633265;7305.707771689153;8566.61450234741;8639.52482613456;8648.740537783846;8654.913450865473;8522.616482258281;8579.571021448903;8580.742577722678;8590.441621294658;8602.78493037676;8403.36974726898;8459.873101903477;8479.2792612628;8487.702930397407;8498;38390.61609383898;38547.80726096351;38559.22881542381;38595;38603.80698096492;3610.891673249807;3628.89113326598;3660.890173294805;3667.7615954962844;3670.1556293872195;31239.843800781076;31454.213644658837;31545.84227078873;31570.526442103386;31575.52636710428;3090.3046814466566;3115.8909438169553;3124.297033167542;3134.984325078383;3135.9372812543847;252500;257881;284849.1515084843;298056.5485862849;298642;38807.61192388096;49332.26001609979;49389.75305123451;49479;49609;51272.48727512752;51311.48688513067;51460.74269628703;51472.970540588976;51495.74252128715;13493.932530337286;13517.797233041512;13652.522161724291;13656.52202172939;13665.658358541115;10955.068819150301;10987.121030317556;11019.724506887393;11035.889641103486;11418.600348987746;11287.661370158909;13781.586552403443;13781.793273100911;13794.517191898436;13798.310084495935;5508.669479831223;5510.311211098613;5524.281843360418;5558.610897237155;5579.637323574036;893.8033632600817;990.9653162139291;1022.1209759606733;1026.9127124194379;1039.4127318065268;18334.81665183358;20339;24411.023559057794;24613;24721.381965451008;1059.1262208677717;1083.1172594335749;1132.5696235430485;1159.721666799962;1175.3476820364608;11147.219694621299;12424.192427492364;12452.937735311265;12552.372381380894;12989.480420783062;306365;306776.46611767245;308919;309212;310116;44781;55188.72405637946;55840.441595584336;55846;56122;1376.3393571085735;1496.880249580031;1497.5282785922618;1546.4587394411865;1566.8119825621;20855.270065547633;21142.78857211408;24181.879090604434;24440.022399103837;24443.38891527726;38323.425148622235;38384.23231535409;38397.61602384052;38399.0400239985;38412;277374;277532;277678;278119;278489;34104.65895341114;34896.127596810285;34897.953061408196;35218.64781352154;35619;44031.458898938385;45339.77330113328;45366;45400.09199816051;45733.856653582596;24164.63753043741;30362.84818575893;30731.078067658007;31480.527792083594;32046;30417.695823041486;30799.846000769852;31159.84420077885;31358;31466;37123.44314835226;37530;37571.436428453046;37881.67414140492;38372.27324770448;31762.52356214705;31765.20586985344;32208.35583288368;32239.677603223667;32251.35497290088;23820.761792381854;24391.878040609685;24785.50428991446;24862.37844053913;25096 07:21:04 INFO - PID 1643 | |2;jquery.html;393.6063936063936;434.92823684092144;435.677598577053;437.5602519467934;452.65824302651515;19797.109130089128;21343.039563219652;21939.561208775835;22016.77983220167;22263.332100037023;22209.666854997187;22237.2216972406;22337;22750.203742869002;23566.646500302508;5930.822075337746;6125.754969801224;6144.6006009609455;6147.47746441553;6153.876922461548;5230.607704422184;6002.96998515009;6134.04922237054;6139.815805525819;6142;5854.473097421232;6050.727717252712;6085.208922840025;6085.604435711687;6113.144159817607;5876;5987.1019347097845;5989.730462129215;6016.42843929826;6027.276726792769;5206.5314121729225;5290.39160496544;5312.840614781563;5342.813001544927;5355.705436201008;26904.865475672694;27149.049783257688;27187.86406067957;27308.726912730817;27423.86288068567;2108.7153234313323;2159.514109325405;2171.913123475059;2172.3157205480225;2175.3365223606793;17643.294268229256;17851.285948562043;17976.280948762032;18131;18208.90895545514;2128.9893550532147;2170.696102545645;2191.7808219178114;2195.769444208367;2197.8681279123143;146954.26522867425;147098.94552370318;147808;147948;149945.99999999892;12198;12685;12767.233666068347;12779.552715654907;12811.975041996619;13552.254625995665;13722.833559147384;16536.917315413346;16561.00633961966;16583.419580314632;8781.780455488537;8978.416402933788;9000.8649870252;9030.774230644154;9150;7586.962065189749;8206.810012548147;8553.44402613828;8562.48625082497;8732.956335218283;6225.439710426083;6231.158793562901;7396.445266604918;7757.301842834172;7893.684252629945;2150.5161338698667;2158.8920553972553;2166.1876796201423;2169.9240526581493;2204.691343211952;820.364217731263;975.1564896364594;983.3902980152209;998.961080476311;1037.305005646219;20709.58580828375;21234.57530849405;21397.57204855894;21473.785262147492;21739.23912663049;949.9002604726543;1016.8627235323285;1021.1371391174446;1032.8347464405658;1034.234666346898;12797.936010319889;13686.931565342109;13868.445262189602;13980.671836175547;14014.948878834128;524969;533060;537096;575253;577841;113722.43138784253;119935.40032299783;134709.3264533671;139871;140081.5991840089;1128.2046157459135;1178.5757127434117;1225.6384366611871;1248.793948998405;1254.2315335377075;18650.53373665615;20872.47818804542;21958.670619941033;22182.66725999079;22250;45021.774891126646;55071;55284.72357638186;56154;57988.13017804821;77601.6278453546;78660.82008769987;78927.60536197282;80000;80421.59789201016;8479.32165426752;8656.22094011542;8780.868286975829;9097.727068187965;9132.178103970675;12792.488300468249;13712.93143534276;13713.245771482665;14035.789463158266;14066.788998164831;9773.657921972697;11748.353840538852;11876.287422754662;12035;12052.638420847388;9382.765430864283;9482.288828337765;10228.28402011852;11027.558897644005;11033.668989930315;11873.525058997544;12026.75946481083;12066.698332541757;12112.576059837864;12209.145359824726;8889.777755556162;8898.688545900863;8986.86519702192;9021.50381728985;9022.774430639023;11612;11708.238964467282;11779.998700110587;11820.645380638594;11903.988161006411 07:21:04 INFO - PID 1643 | |3;mootools.html;1211.7515909238605;1317.8220940173073;1438.3743071763784;1459.7664973097735;1461.1306272767706;11316.377599232039;12180.390980450975;12183;12192.329421881794;12461.875381246185;12143.271403715784;12290.38548072595;12313.322767247797;12323.075769317293;12361.19652222605;2599.3111825366245;2637.3406648337914;2639.934001649955;2641.3660721426913;2643.7224091470407;2319.7100362454694;2493.6758221431187;2540.9745902541017;2585.6250843627663;2589.2361753282735;2521.9747802522015;2530.7975361971;2570.3959569501153;2570.8586027768465;2574.703909050455;2547.694276686791;2570.0362364113457;2597.1559243245833;2601.0636170978432;2601.7398260174023;2419.7459266777087;2551.783098436635;2567.101514469939;2588.1459118491043;2599.129291687287;11875.049996000385;11976;12071.93964030183;12072.939635301855;12087.879121208764;6635.834104147387;6820.931790682129;6865.897011544831;6869.519133660646;6878.243393226791;3788.886333410002;4073.97963010186;4338.089001309729;4405.559444055601;4427.822887084512;1227.2391117507113;1227.9324637144953;1256.4534427524056;1263.434404818497;1283.3198404845448;12833.550825721055;16694.66610667792;16813.327466901308;16865.494035178963;16900.915495422567;4708.764561771898;5422.5119739223655;5447.264619276426;5619.157126430986;5712.7714891403875;11860.525578976916;11933.343666098448;11990.220635658654;11993.520259189534;12004.819927701092;6290.339514350927;6330.746770129235;6335.239771227482;6341.778037768656;6346.746130154835;5298;5843.503302219274;5931.3178984417345;6018.729157187937;6286.717097730613;4313.827446902089;4434.312681534368;4442;4456.398386217884;4471.306947423154;1295.8898493628146;1298.8246586710861;1304.934753262333;1312.297920612487;1314.4413624209672;793.1750978981794;793.1830214878685;815.9143289954469;822.7737372222567;840.033960944906;6122.938770612326;6174.722137503823;6337.809865704036;6403.199600049994;6405.615663060231;535.9169328754049;547.6659237864899;592.937741537141;598.5929567893787;609;4224.260754367973;4348.891277718019;4360.215161271001;4497.527759585262;4748.335233067373;14961.251937403304;15080.472183473745;15087.547373578811;15225.695486090437;15421.691566168614;14864.256787160599;14956.0278581894;15473.071615703095;15536.223188840739;15571.221438928009;8543.487390756574;8678.95660521706;8825;8832.735017949472;8842.823143537093;6705.530612857053;6721.865562688817;7017.614031228127;7051.153861536648;7081.044059052168;13446.327683615977;13639.79540306916;13650.453981840616;13655.04414690962;13787.379567919668;13774.035817492679;13943.30283485842;14036.508722194674;14112.647183820487;14407.927960360132;7939.245771651684;9340.81318373615;9362.95318523403;9378.343515954091;9462.2430205582;10559.788804224027;10741.462926853783;10747.355158690503;10750.569977200825;10768.300060496043;8991.820163596822;9210.447373157363;9330.066993300616;9348.813023739624;9377.765555861159;9014.819703606023;9039.186473217442;9040.502772347321;9117.270618350649;9120.5439728012;6877.621730804653;7941.523508589503;8094.87857682147;8110.918890811016;8118.512889226666;6002.729877155626;6020.277566692025;6100.877982440237;6175.876482470236;6203.937960620336;4531.524189960139;5935.605132793704;6059.697015149137;6099.268087829489;6194.14175747283 07:21:04 INFO - PID 1643 | |4;prototype.html;354.1800731307024;396.7500474700938;400.1616613195355;405.60250954065026;405.64303412996543;4747;5747.626404283724;5932.614380065298;6125.846853828656;6306.274778400485;6020.819375418733;6133.693315334238;6145.692715364236;6156.107364432155;6175.197224360827;3851.9407163030182;4273.465816772903;4527.800132964767;4537.841175558856;4551.635869130479;3736.719746019046;4492.97753511232;4498.482674492426;4510.774461276939;4560.33875088112;4488.124815660947;4493.370928070073;4494.303382975644;4511.35335602506;4522.706024108423;3527.488514165457;4031.475908131924;4369.628935457495;4491.573300536444;4524.00471896184;3166.4458719723957;3609.675129238381;3830.655241028293;3831.4252862070634;3852.8844134676006;5702.344230413514;6024.397560243984;6041.6375017499095;6044.335123136496;6058.515318774532;5206.479352064801;5298.655587386807;5317.255584218213;5333.4399888011585;5356.625036247464;6518.902216466757;6559.2128944526485;6569;6573.769918052893;6601.438877695402;4232.386303985935;4322.8703138905885;4330.891727706832;4339.045410009793;4342.891427714301;5742.368339482654;6672.766453174116;6829.453643708492;6886.380225779704;6992.825179370506;3523.3481805865486;4127.628513433745;4224.535301116936;4413.0070734084575;4417.185268057769;3685.447182922583;4322.69741118125;4480.395146655225;4491.14668213045;4491.371208030879;4427.667924905645;4464.192002239099;5163.948360516422;5249.658772179872;5328.229320753187;6633.402993730588;6648.434883034899;6648.902931016425;6661.433778128817;6671.33286671329;1278.622806272152;1358.531306699187;1493.7759336099455;1498.0786816108016;1528.7477566201674;945.239082538563;996.6296342529023;997.6308765756717;1015.263933648114;1015.6546774096843;5702.54379649627;6118.388161183935;6294.622322660563;6310.400511951358;6324.525660575475;5945.970270148621;6116.715489747114;6229.595076320024;6815.352541508549;6822.317768223138;5004.84985450437;5132.203728694927;5314.2825718528275;6300.361176746877;6391.520635952322;3140.450421176285;3144.1039303798307;3165.920851978719;3595.6044835068124;3598.226381328027;6613.875641141018;6884.827879303058;7099.680514376865;7107;7119.857602847914;6140.907886381708;6156.538259630456;7020.157581090302;7040.155181378165;7082.008518807371;5821.446962538552;6898.964805382324;6983.511154219156;7009.544379615309;7080.0795896534155;6367.554271200971;6851.143607049119;6884.347756538295;6918.7232510699005;6940.640182338029;6532.673366331569;6676.6297964222;6699.698513566998;6710.865782684416;6739.764108256188;6592.373724496165;6758.78341898463;6925.89611155823;7122.643867806485;7170.103737032871;4128.752274863517;4142.647874930544;4396.51638319791;4402.449693788277;4412.338149277634;5666.291713535808;5997.260794369584;6387.648679322682;6958.930410695828;7209.783706488814;5430.5384042356845;5696;6546.116274302908;6680.799576012728;6695.363940425652;5287.471252874681;6126.877462450815;6584.539082264196;6657.500687448364;6679.198496180489;5336;6294.71673774672;6511.66510865281;6546.672666366758;6586.497131090155;6475;6633.70148343316;6668.933310666831;6699.0621313015245;6699.129113215251;5678.3753787084215;6337.15587165869;6437.742490300336;6492.740290388331;6546.901796472961 07:21:04 INFO - PID 1643 | |5;yui.html;744.2631794523422;811.9188081191885;816.9264766171043;848.6053984897021;853.9581710313417;14384.280785960686;14733.852661473382;14869.628259293522;16540.91729541352;16945.661086778273;14435.783463248059;16216.594585135377;16342.264598093085;16408;17629;977.4330888084894;982.3025651787204;996.1582462818907;1002.6240159940022;1006.9879770830305;996.1681995533702;999.5851721535554;1000.309786247487;1004.4575928998332;1005.1757558801786;1040.0795296162912;1047.9109275711573;1064.7125276175427;1065.1425602390073;1066.568039943824;1033.152814691953;1054.104011590146;1057.9523921423554;1059.0680201422776;1062.0229388962173;766.5132640773094;775.9573223472763;778.5289899610767;779.9181085985947;782.9334506566948;14103.435862565486;14355.425782968668;16599.41902033423;17395.913020434942;17408.30366785327;16733.24700388473;17110.058946758047;17573;17671.911640441846;17746.20142093609;14325.57023289303;14784.334704938303;14825.036372635745;14906.925465372713;14910.925445372812;3174.158847905307;3237.838108094609;3379.357921994838;3383.204946837491;3603.513525674027;105528.47235763849;105799;115472.42263788711;115848.84151158464;116944.83055169425;13337.866621333857;13523;13607.727845443036;13659.658508537168;13665.590032299047;19778.901105494668;25785.226443206735;25941.870290648425;25956.0915367965;26365.20904372872;6873.381395674413;7215.025971493887;7259.6007219603425;7279.526830755984;7282.96358518204;3342.5320455136302;3860.9613903861164;3939.546952100488;3942.42834788954;3951.249262640089;4151.0037590977945;4228.344606585984;4292.613664770186;4408.735475871458;4445.844395446144;907.945523268606;925.0656836595087;941.6468824190929;942.886853577575;958.5830163878735;628.9716962736687;639.8496353356958;647.2071712152575;657.4707360574688;679.0154276299386;10946.835797463045;12084.456199471046;12112.333821639719;12170.14808963364;12300.876991230152;643.1446176585131;649.1074772188241;650.1580453312877;655.1908393134476;668.7024274197935;4157.5010998679745;5509.862253443696;5579.721013949367;5687.7156142192725;5783.913241301384;87286.1271387278;88432.55783721169;90474.09525904788;93764;114192;81396.18603813885;83333.3333333342;90514.99999999868;92927;94931;84970;85301.57349213214;85658;85824.57087714647;88434;11363.37501437429;12092;12121.969632580976;12677.17598356104;12812.679683008;28853.42293154167;33027.33945321128;36275;39881.00297492586;40532.960027998604;54490;67079.98760037207;68567.65716171387;70027.29972700411;70970.29029709636;16019.599510012344;16735.832641673915;17014;17082.572935676708;17451.04019278952;17808.287668493114;18605.348812791486;18683.439496815117;18704.812951870306;18751.249950001846;15795.21023948821;16324.510264692079;16562.254698538836;16940.491785246464;16984.15079246008;17183.828161718724;17353.652926941137;17638.02990835465;17692.699949006546;17718.82281177172;16905.323787048874;17365.305387784854;17427.302907883543;17567;17591.384301549384;11704.356260405759;11847.76304473923;12108.75782484363;12148.21036632616;12560.560380386643;13274.004449666121;13450.663733406353;13990.720185596434;14022.368993395117;14217.928910355382 07:21:04 INFO - PID 1643 | __end_tp_report 07:21:04 INFO - PID 1643 | __start_cc_report 07:21:04 INFO - PID 1643 | _x_x_mozilla_cycle_collect,414 07:21:04 INFO - PID 1643 | __end_cc_report 07:21:04 INFO - PID 1643 | __startTimestamp1494598864084__endTimestamp 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | ------- Summary: start ------- 07:21:04 INFO - PID 1643 | Number of tests: 6 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6091.78 Median:6370.17 stddev:756.70 (11.9%) stddev-sans-first:719.83 07:21:04 INFO - PID 1643 | Values: 3027.8 3219.8 3526.3 3540.4 3588.8 4597.9 6087.5 6125.0 6134.0 6181.9 6105.4 6126.0 6131.4 6141.9 6186.2 3999.8 4910.0 5139.8 5358.9 5501.1 5390.8 5456.2 5515.9 5522.0 5522.2 5438.5 5479.3 5487.9 5533.8 5543.0 5317.6 5422.8 5434.8 5441.2 5450.6 5389.4 5393.2 5394.8 5462.5 5486.9 5938.0 6157.8 6285.4 6293.7 6298.2 6572.3 6646.2 6652.5 6660.2 6670.9 6456.2 6498.9 6664.3 6719.1 6719.2 6316.7 6329.7 6336.7 6339.9 6367.6 6774.5 6813.6 6817.5 6869.3 6892.3 6306.9 6376.0 6421.1 6424.1 6480.5 6636.8 6662.4 6682.4 6693.6 6702.6 6435.2 6569.6 6579.1 6592.2 6603.1 6328.7 6418.4 6509.9 6607.2 6624.2 5105.6 5261.3 5274.3 5280.8 5297.0 4628.7 4667.3 4697.6 4708.5 4728.0 6208.4 6274.8 6325.9 6365.8 6367.7 6570.6 6614.1 6656.2 6694.1 6751.3 6344.4 6357.0 6370.2 6376.0 6392.0 5845.2 5866.5 5872.9 5880.6 5884.4 6654.1 6760.0 6782.7 6787.1 6936.5 6664.3 6701.8 6702.6 6725.3 6726.9 6520.0 6570.0 6773.9 6789.6 6867.0 6584.6 6602.1 6622.5 6627.9 6642.3 6487.3 6560.2 6572.1 6700.0 6740.1 5403.9 6348.4 6398.1 6467.6 6527.3 5253.6 6356.8 6482.4 6482.9 6492.8 6234.8 6378.5 6382.4 6382.7 6418.4 6304.6 6315.1 6451.2 6473.7 6479.9 6348.3 6379.0 6383.3 6383.7 6388.3 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:44391.16 Median:34500.39 stddev:75599.48 (219.1%) stddev-sans-first:75740.34 07:21:04 INFO - PID 1643 | Values: 687.3 784.1 818.3 1202.7 1265.8 40047.8 41148.6 41206.0 41209.8 41220.6 41331.6 41750.0 41875.8 41892.4 41970.8 7880.0 8011.5 8398.9 8572.1 8591.9 8484.9 8586.6 8595.3 8598.9 8599.4 7305.7 8566.6 8639.5 8648.7 8654.9 8522.6 8579.6 8580.7 8590.4 8602.8 8403.4 8459.9 8479.3 8487.7 8498.0 38390.6 38547.8 38559.2 38595.0 38603.8 3610.9 3628.9 3660.9 3667.8 3670.2 31239.8 31454.2 31545.8 31570.5 31575.5 3090.3 3115.9 3124.3 3135.0 3135.9 252500.0 257881.0 284849.2 298056.5 298642.0 38807.6 49332.3 49389.8 49479.0 49609.0 51272.5 51311.5 51460.7 51473.0 51495.7 13493.9 13517.8 13652.5 13656.5 13665.7 10955.1 10987.1 11019.7 11035.9 11418.6 11287.7 13781.6 13781.8 13794.5 13798.3 5508.7 5510.3 5524.3 5558.6 5579.6 893.8 991.0 1022.1 1026.9 1039.4 18334.8 20339.0 24411.0 24613.0 24721.4 1059.1 1083.1 1132.6 1159.7 1175.3 11147.2 12424.2 12452.9 12552.4 12989.5 306365.0 306776.5 308919.0 309212.0 310116.0 44781.0 55188.7 55840.4 55846.0 56122.0 1376.3 1496.9 1497.5 1546.5 1566.8 20855.3 21142.8 24181.9 24440.0 24443.4 38323.4 38384.2 38397.6 38399.0 38412.0 277374.0 277532.0 277678.0 278119.0 278489.0 34104.7 34896.1 34898.0 35218.6 35619.0 44031.5 45339.8 45366.0 45400.1 45733.9 24164.6 30362.8 30731.1 31480.5 32046.0 30417.7 30799.8 31159.8 31358.0 31466.0 37123.4 37530.0 37571.4 37881.7 38372.3 31762.5 31765.2 32208.4 32239.7 32251.4 23820.8 24391.9 24785.5 24862.4 25096.0 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:35406.37 Median:22223.44 stddev:93095.63 (418.9%) stddev-sans-first:93319.67 07:21:04 INFO - PID 1643 | Values: 393.6 434.9 435.7 437.6 452.7 19797.1 21343.0 21939.6 22016.8 22263.3 22209.7 22237.2 22337.0 22750.2 23566.6 5930.8 6125.8 6144.6 6147.5 6153.9 5230.6 6003.0 6134.0 6139.8 6142.0 5854.5 6050.7 6085.2 6085.6 6113.1 5876.0 5987.1 5989.7 6016.4 6027.3 5206.5 5290.4 5312.8 5342.8 5355.7 26904.9 27149.0 27187.9 27308.7 27423.9 2108.7 2159.5 2171.9 2172.3 2175.3 17643.3 17851.3 17976.3 18131.0 18208.9 2129.0 2170.7 2191.8 2195.8 2197.9 146954.3 147098.9 147808.0 147948.0 149946.0 12198.0 12685.0 12767.2 12779.6 12812.0 13552.3 13722.8 16536.9 16561.0 16583.4 8781.8 8978.4 9000.9 9030.8 9150.0 7587.0 8206.8 8553.4 8562.5 8733.0 6225.4 6231.2 7396.4 7757.3 7893.7 2150.5 2158.9 2166.2 2169.9 2204.7 820.4 975.2 983.4 999.0 1037.3 20709.6 21234.6 21397.6 21473.8 21739.2 949.9 1016.9 1021.1 1032.8 1034.2 12797.9 13686.9 13868.4 13980.7 14014.9 524969.0 533060.0 537096.0 575253.0 577841.0 113722.4 119935.4 134709.3 139871.0 140081.6 1128.2 1178.6 1225.6 1248.8 1254.2 18650.5 20872.5 21958.7 22182.7 22250.0 45021.8 55071.0 55284.7 56154.0 57988.1 77601.6 78660.8 78927.6 80000.0 80421.6 8479.3 8656.2 8780.9 9097.7 9132.2 12792.5 13712.9 13713.2 14035.8 14066.8 9773.7 11748.4 11876.3 12035.0 12052.6 9382.8 9482.3 10228.3 11027.6 11033.7 11873.5 12026.8 12066.7 12112.6 12209.1 8889.8 8898.7 8986.9 9021.5 9022.8 11612.0 11708.2 11780.0 11820.6 11904.0 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:7162.70 Median:3931.43 stddev:4581.52 (116.5%) stddev-sans-first:4572.55 07:21:04 INFO - PID 1643 | Values: 1211.8 1317.8 1438.4 1459.8 1461.1 11316.4 12180.4 12183.0 12192.3 12461.9 12143.3 12290.4 12313.3 12323.1 12361.2 2599.3 2637.3 2639.9 2641.4 2643.7 2319.7 2493.7 2541.0 2585.6 2589.2 2522.0 2530.8 2570.4 2570.9 2574.7 2547.7 2570.0 2597.2 2601.1 2601.7 2419.7 2551.8 2567.1 2588.1 2599.1 11875.0 11976.0 12071.9 12072.9 12087.9 6635.8 6820.9 6865.9 6869.5 6878.2 3788.9 4074.0 4338.1 4405.6 4427.8 1227.2 1227.9 1256.5 1263.4 1283.3 12833.6 16694.7 16813.3 16865.5 16900.9 4708.8 5422.5 5447.3 5619.2 5712.8 11860.5 11933.3 11990.2 11993.5 12004.8 6290.3 6330.7 6335.2 6341.8 6346.7 5298.0 5843.5 5931.3 6018.7 6286.7 4313.8 4434.3 4442.0 4456.4 4471.3 1295.9 1298.8 1304.9 1312.3 1314.4 793.2 793.2 815.9 822.8 840.0 6122.9 6174.7 6337.8 6403.2 6405.6 535.9 547.7 592.9 598.6 609.0 4224.3 4348.9 4360.2 4497.5 4748.3 14961.3 15080.5 15087.5 15225.7 15421.7 14864.3 14956.0 15473.1 15536.2 15571.2 8543.5 8679.0 8825.0 8832.7 8842.8 6705.5 6721.9 7017.6 7051.2 7081.0 13446.3 13639.8 13650.5 13655.0 13787.4 13774.0 13943.3 14036.5 14112.6 14407.9 7939.2 9340.8 9363.0 9378.3 9462.2 10559.8 10741.5 10747.4 10750.6 10768.3 8991.8 9210.4 9330.1 9348.8 9377.8 9014.8 9039.2 9040.5 9117.3 9120.5 6877.6 7941.5 8094.9 8110.9 8118.5 6002.7 6020.3 6100.9 6175.9 6203.9 4531.5 5935.6 6059.7 6099.3 6194.1 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5257.17 Median:6022.61 stddev:1710.71 (28.4%) stddev-sans-first:1675.46 07:21:04 INFO - PID 1643 | Values: 354.2 396.8 400.2 405.6 405.6 4747.0 5747.6 5932.6 6125.8 6306.3 6020.8 6133.7 6145.7 6156.1 6175.2 3851.9 4273.5 4527.8 4537.8 4551.6 3736.7 4493.0 4498.5 4510.8 4560.3 4488.1 4493.4 4494.3 4511.4 4522.7 3527.5 4031.5 4369.6 4491.6 4524.0 3166.4 3609.7 3830.7 3831.4 3852.9 5702.3 6024.4 6041.6 6044.3 6058.5 5206.5 5298.7 5317.3 5333.4 5356.6 6518.9 6559.2 6569.0 6573.8 6601.4 4232.4 4322.9 4330.9 4339.0 4342.9 5742.4 6672.8 6829.5 6886.4 6992.8 3523.3 4127.6 4224.5 4413.0 4417.2 3685.4 4322.7 4480.4 4491.1 4491.4 4427.7 4464.2 5163.9 5249.7 5328.2 6633.4 6648.4 6648.9 6661.4 6671.3 1278.6 1358.5 1493.8 1498.1 1528.7 945.2 996.6 997.6 1015.3 1015.7 5702.5 6118.4 6294.6 6310.4 6324.5 5946.0 6116.7 6229.6 6815.4 6822.3 5004.8 5132.2 5314.3 6300.4 6391.5 3140.5 3144.1 3165.9 3595.6 3598.2 6613.9 6884.8 7099.7 7107.0 7119.9 6140.9 6156.5 7020.2 7040.2 7082.0 5821.4 6899.0 6983.5 7009.5 7080.1 6367.6 6851.1 6884.3 6918.7 6940.6 6532.7 6676.6 6699.7 6710.9 6739.8 6592.4 6758.8 6925.9 7122.6 7170.1 4128.8 4142.6 4396.5 4402.4 4412.3 5666.3 5997.3 6387.6 6958.9 7209.8 5430.5 5696.0 6546.1 6680.8 6695.4 5287.5 6126.9 6584.5 6657.5 6679.2 5336.0 6294.7 6511.7 6546.7 6586.5 6475.0 6633.7 6668.9 6699.1 6699.1 5678.4 6337.2 6437.7 6492.7 6546.9 07:21:04 INFO - PID 1643 | 07:21:04 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:21393.75 Median:17777.24 stddev:29199.46 (164.3%) stddev-sans-first:29240.20 07:21:04 INFO - PID 1643 | Values: 744.3 811.9 816.9 848.6 854.0 14384.3 14733.9 14869.6 16540.9 16945.7 14435.8 16216.6 16342.3 16408.0 17629.0 977.4 982.3 996.2 1002.6 1007.0 996.2 999.6 1000.3 1004.5 1005.2 1040.1 1047.9 1064.7 1065.1 1066.6 1033.2 1054.1 1058.0 1059.1 1062.0 766.5 776.0 778.5 779.9 782.9 14103.4 14355.4 16599.4 17395.9 17408.3 16733.2 17110.1 17573.0 17671.9 17746.2 14325.6 14784.3 14825.0 14906.9 14910.9 3174.2 3237.8 3379.4 3383.2 3603.5 105528.5 105799.0 115472.4 115848.8 116944.8 13337.9 13523.0 13607.7 13659.7 13665.6 19778.9 25785.2 25941.9 25956.1 26365.2 6873.4 7215.0 7259.6 7279.5 7283.0 3342.5 3861.0 3939.5 3942.4 3951.2 4151.0 4228.3 4292.6 4408.7 4445.8 907.9 925.1 941.6 942.9 958.6 629.0 639.8 647.2 657.5 679.0 10946.8 12084.5 12112.3 12170.1 12300.9 643.1 649.1 650.2 655.2 668.7 4157.5 5509.9 5579.7 5687.7 5783.9 87286.1 88432.6 90474.1 93764.0 114192.0 81396.2 83333.3 90515.0 92927.0 94931.0 84970.0 85301.6 85658.0 85824.6 88434.0 11363.4 12092.0 12122.0 12677.2 12812.7 28853.4 33027.3 36275.0 39881.0 40533.0 54490.0 67080.0 68567.7 70027.3 70970.3 16019.6 16735.8 17014.0 17082.6 17451.0 17808.3 18605.3 18683.4 18704.8 18751.2 15795.2 16324.5 16562.3 16940.5 16984.2 17183.8 17353.7 17638.0 17692.7 17718.8 16905.3 17365.3 17427.3 17567.0 17591.4 11704.4 11847.8 12108.8 12148.2 12560.6 13274.0 13450.7 13990.7 14022.4 14217.9 07:21:04 INFO - PID 1643 | -------- Summary: end -------- 07:21:04 INFO - PID 1643 | 07:21:04 INFO - TEST-INFO | 1643: exit 0 07:21:04 INFO - TEST-OK | dromaeo_css | took 1114620ms 07:21:04 INFO - TEST-START | kraken 07:21:04 INFO - operating with platform_type : mac_ 07:21:04 INFO - Initialising browser for kraken test... 07:21:04 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp1MpQ52/profile http://localhost:49225/getInfo.html) 07:21:07 INFO - PID 1657 | 2017-05-12 07:21:07.074 plugin-container[1658:10504] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 07:21:07 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:07 INFO - PID 1657 | 2017-05-12 07:21:07.077 plugin-container[1658:10504] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 07:21:07 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:07 INFO - PID 1657 | 2017-05-12 07:21:07.077 plugin-container[1658:10504] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:21:07 INFO - PID 1657 | __metrics Screen width/height:1600/1200 07:21:07 INFO - PID 1657 | colorDepth:24 07:21:07 INFO - PID 1657 | Browser inner width/height: 1024/691 07:21:07 INFO - PID 1657 | __metrics 07:21:07 INFO - TEST-INFO | 1657: exit 0 07:21:07 INFO - Browser initialized. 07:21:07 INFO - Running cycle 1/1 for kraken test... 07:21:07 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmp1MpQ52/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:21:09 INFO - PID 1659 | 2017-05-12 07:21:09.975 plugin-container[1660:10629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9237, name = 'com.apple.tsm.portname' 07:21:09 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:09 INFO - PID 1659 | 2017-05-12 07:21:09.976 plugin-container[1660:10629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 07:21:09 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:09 INFO - PID 1659 | 2017-05-12 07:21:09.976 plugin-container[1660:10629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:21:10 INFO - PID 1659 | 2017-05-12 07:21:10.529 plugin-container[1661:10677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 07:21:10 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:10 INFO - PID 1659 | 2017-05-12 07:21:10.530 plugin-container[1661:10677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 07:21:10 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:21:10 INFO - PID 1659 | 2017-05-12 07:21:10.530 plugin-container[1661:10677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:21:19 INFO - PID 1659 | RSS: Main: 229797888 07:21:19 INFO - PID 1659 | 07:21:22 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 07:21:23 INFO - PID 1659 | RSS: Main: 243445760 07:21:23 INFO - PID 1659 | 07:21:25 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 07:21:26 INFO - PID 1659 | RSS: Main: 251465728 07:21:26 INFO - PID 1659 | 07:21:29 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 07:21:30 INFO - PID 1659 | RSS: Main: 253964288 07:21:30 INFO - PID 1659 | 07:21:32 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 07:21:33 INFO - PID 1659 | RSS: Main: 255664128 07:21:33 INFO - PID 1659 | 07:21:34 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:21:35 INFO - PID 1659 | RSS: Main: 255823872 07:21:35 INFO - PID 1659 | 07:21:38 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 07:21:39 INFO - PID 1659 | RSS: Main: 262840320 07:21:39 INFO - PID 1659 | 07:21:42 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 07:21:42 INFO - PID 1659 | RSS: Main: 264900608 07:21:42 INFO - PID 1659 | 07:21:45 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 07:21:46 INFO - PID 1659 | RSS: Main: 264204288 07:21:46 INFO - PID 1659 | 07:21:47 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:21:48 INFO - PID 1659 | RSS: Main: 263626752 07:21:48 INFO - PID 1659 | 07:21:50 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:21:50 INFO - PID 1659 | RSS: Main: 263987200 07:21:50 INFO - PID 1659 | 07:21:52 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:21:53 INFO - PID 1659 | RSS: Main: 263491584 07:21:53 INFO - PID 1659 | 07:21:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:21:57 INFO - PID 1659 | RSS: Main: 263524352 07:21:57 INFO - PID 1659 | 07:22:00 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:22:01 INFO - PID 1659 | RSS: Main: 263700480 07:22:01 INFO - PID 1659 | 07:22:02 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 07:22:03 INFO - PID 1659 | RSS: Main: 265535488 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | __start_tp_report 07:22:03 INFO - PID 1659 | _x_x_mozilla_page_load 07:22:03 INFO - PID 1659 | _x_x_mozilla_page_load_details 07:22:03 INFO - PID 1659 | |i|pagename|runs| 07:22:03 INFO - PID 1659 | |0;ai-astar;104;116;114;114;114;114;114;114;114;113 07:22:03 INFO - PID 1659 | |1;audio-beat-detection;160;107;110;148;108;111;116;118;138;113 07:22:03 INFO - PID 1659 | |2;audio-dft;195;197;197;203;207;210;212;209;199;200 07:22:03 INFO - PID 1659 | |3;audio-fft;83;58;59;82;63;62;89;85;93;64 07:22:03 INFO - PID 1659 | |4;audio-oscillator;87;69;82;67;67;68;82;70;68;90 07:22:03 INFO - PID 1659 | |5;imaging-gaussian-blur;89;90;92;88;89;88;88;90;89;89 07:22:03 INFO - PID 1659 | |6;imaging-darkroom;97;97;96;144;96;105;97;96;118;156 07:22:03 INFO - PID 1659 | |7;imaging-desaturate;92;94;91;91;89;96;107;92;90;90 07:22:03 INFO - PID 1659 | |8;json-parse-financial;52;48;49;51;50;51;50;53;50;49 07:22:03 INFO - PID 1659 | |9;json-stringify-tinderbox;60;69;93;82;58;58;56;56;55;55 07:22:03 INFO - PID 1659 | |10;stanford-crypto-aes;81;84;81;83;82;84;82;84;82;86 07:22:03 INFO - PID 1659 | |11;stanford-crypto-ccm;155;148;128;137;140;140;146;160;225;151 07:22:03 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;231;238;187;186;184;183;187;195;230;183 07:22:03 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;71;69;67;62;62;83;100;71;90;97 07:22:03 INFO - PID 1659 | __end_tp_report 07:22:03 INFO - PID 1659 | __start_cc_report 07:22:03 INFO - PID 1659 | _x_x_mozilla_cycle_collect,997 07:22:03 INFO - PID 1659 | __end_cc_report 07:22:03 INFO - PID 1659 | __startTimestamp1494598923195__endTimestamp 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | ------- Summary: start ------- 07:22:03 INFO - PID 1659 | Number of tests: 14 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:3.28 (2.9%) stddev-sans-first:0.78 07:22:03 INFO - PID 1659 | Values: 104.0 116.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 113.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:117.00 stddev:18.82 (16.1%) stddev-sans-first:14.39 07:22:03 INFO - PID 1659 | Values: 160.0 107.0 110.0 148.0 108.0 111.0 116.0 118.0 138.0 113.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:202.90 Median:205.00 stddev:6.17 (3.0%) stddev-sans-first:5.85 07:22:03 INFO - PID 1659 | Values: 195.0 197.0 197.0 203.0 207.0 210.0 212.0 209.0 199.0 200.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:73.80 Median:82.50 stddev:13.73 (16.6%) stddev-sans-first:14.16 07:22:03 INFO - PID 1659 | Values: 83.0 58.0 59.0 82.0 63.0 62.0 89.0 85.0 93.0 64.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:76.00 stddev:9.15 (12.0%) stddev-sans-first:8.62 07:22:03 INFO - PID 1659 | Values: 87.0 69.0 82.0 67.0 67.0 68.0 82.0 70.0 68.0 90.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 07:22:03 INFO - PID 1659 | Values: 89.0 90.0 92.0 88.0 89.0 88.0 88.0 90.0 89.0 89.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:110.20 Median:96.00 stddev:22.25 (23.2%) stddev-sans-first:23.08 07:22:03 INFO - PID 1659 | Values: 97.0 97.0 96.0 144.0 96.0 105.0 97.0 96.0 118.0 156.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:91.50 stddev:5.27 (5.8%) stddev-sans-first:5.57 07:22:03 INFO - PID 1659 | Values: 92.0 94.0 91.0 91.0 89.0 96.0 107.0 92.0 90.0 90.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.49 (3.0%) stddev-sans-first:1.45 07:22:03 INFO - PID 1659 | Values: 52.0 48.0 49.0 51.0 50.0 51.0 50.0 53.0 50.0 49.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:59.00 stddev:13.20 (22.4%) stddev-sans-first:13.91 07:22:03 INFO - PID 1659 | Values: 60.0 69.0 93.0 82.0 58.0 58.0 56.0 56.0 55.0 55.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.50 stddev:1.60 (1.9%) stddev-sans-first:1.54 07:22:03 INFO - PID 1659 | Values: 81.0 84.0 81.0 83.0 82.0 84.0 82.0 84.0 82.0 86.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:149.50 stddev:26.94 (18.0%) stddev-sans-first:28.57 07:22:03 INFO - PID 1659 | Values: 155.0 148.0 128.0 137.0 140.0 140.0 146.0 160.0 225.0 151.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.40 Median:191.00 stddev:22.84 (12.0%) stddev-sans-first:21.38 07:22:03 INFO - PID 1659 | Values: 231.0 238.0 187.0 186.0 184.0 183.0 187.0 195.0 230.0 183.0 07:22:03 INFO - PID 1659 | 07:22:03 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:71.00 stddev:14.22 (20.0%) stddev-sans-first:14.90 07:22:03 INFO - PID 1659 | Values: 71.0 69.0 67.0 62.0 62.0 83.0 100.0 71.0 90.0 97.0 07:22:03 INFO - PID 1659 | -------- Summary: end -------- 07:22:03 INFO - PID 1659 | 07:22:04 INFO - TEST-INFO | 1659: exit 0 07:22:04 INFO - TEST-OK | kraken | took 59361ms 07:22:04 INFO - SUITE-END | took 1173s 07:22:04 INFO - Completed test suite (00:19:34) 07:22:04 INFO - javascript benchmark 07:22:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7842.701852824172, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.8191505312925, 3219.758518111142, 3526.277113191796, 3540.433530635099, 3588.838502267398, 4597.89650483884, 6087.452129308362, 6125.0, 6134.049222370534, 6181.938180618193, 6105.399096011525, 6125.989211780062, 6131.376666650039, 6141.907871381933, 6186.226721659793, 3999.7600143991453, 4909.950900491003, 5139.794408223685, 5358.9282143571445, 5501.14732216507, 5390.8382748517415, 5456.208849716789, 5515.862103447426, 5521.978433989705, 5522.171674248874, 5438.510534051954, 5479.260299859528, 5487.890242195173, 5533.750981205835, 5543.029969755296, 5317.601179911522, 5422.83731488052, 5434.83695489136, 5441.238226648273, 5450.645708028966, 5389.407165211824, 5393.2179833923865, 5394.838154855361, 5462.5083742462975, 5486.91769623456, 5937.970310148465, 6157.78447754331, 6285.4343109119945, 6293.653849038301, 6298.244210694746, 6572.309907459744, 6646.169228846395, 6652.501062420338, 6660.23407308156, 6670.866582668368, 6456.192975878015, 6498.935010649928, 6664.300248473889, 6719.126513553256, 6719.2272888617945, 6316.652584107918, 6329.651869147195, 6336.714847831859, 6339.936600634027, 6367.554271201064, 6774.525783195228, 6813.557118787263, 6817.454603631797, 6869.278725733826, 6892.345227203407, 6306.936930630727, 6375.968120159462, 6421.068945003042, 6424.1327420798525, 6480.51396145291, 6636.800895973129, 6662.433693136138, 6682.431993280626, 6693.598384096969, 6702.631355275506, 6435.195600549931, 6569.572977756526, 6579.078928949855, 6592.208934927839, 6603.075569420285, 6328.6835658216905, 6418.358164183544, 6509.934900651027, 6607.207135143717, 6624.238212605515, 5105.591552675852, 5261.316028916216, 5274.340707411574, 5280.841574752687, 5297.0, 4628.67599268048, 4667.253239481666, 4697.647676424282, 4708.529147085333, 4727.952720472751, 6208.379162083846, 6274.811755647338, 6325.9051114233325, 6365.809025729235, 6367.681615919279, 6570.605763654196, 6614.140161778938, 6656.167979002625, 6694.09629699994, 6751.291114433012, 6344.365563443619, 6357.014662727192, 6370.1718776558755, 6375.979843225062, 6391.9680401598625, 5845.210896528999, 5866.501347385435, 5872.911906321408, 5880.617759845596, 5884.352721200665, 6654.134962455044, 6760.019797129487, 6782.660866956534, 6787.117674702158, 6936.4797640176175, 6664.300248473841, 6701.8324541884895, 6702.631355275506, 6725.327467253235, 6726.932730672631, 6520.0, 6569.967150164219, 6773.93226067733, 6789.592624442549, 6867.0, 6584.572002819993, 6602.075709400784, 6622.503312251504, 6627.867442651023, 6642.302558231316, 6487.286398496256, 6560.212774467094, 6572.14562106923, 6700.0, 6740.090087838079, 5403.891922161613, 6348.428641422294, 6398.072279519351, 6467.579607325697, 6527.281998980014, 5253.632245742914, 6356.77751278703, 6482.448991835747, 6482.935170648233, 6492.772752953624, 6234.844128896814, 6378.457831084224, 6382.393672601095, 6382.680865956777, 6418.390252925965, 6304.621722696653, 6315.147455093504, 6451.161349024785, 6473.741050357933, 6479.870402592016, 6348.269948955744, 6378.96810515963, 6383.329750376143, 6383.712732926937, 6388.329225431448], "value": 6038.90419762867, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.3298533929419, 784.0787075186656, 818.2676504528446, 1202.741410596721, 1265.8164566137916, 40047.79976100119, 41148.58851411485, 41206.0, 41209.793951030275, 41220.58779412209, 41331.586684133224, 41750.0, 41875.79062104693, 41892.371614425734, 41970.79014604923, 7879.999999999985, 8011.479253848511, 8398.874016889751, 8572.14278572141, 8591.871121933176, 8484.915150848505, 8586.570671466432, 8595.312375010015, 8598.87101693472, 8599.44103633265, 7305.707771689153, 8566.61450234741, 8639.52482613456, 8648.740537783846, 8654.913450865473, 8522.616482258281, 8579.571021448903, 8580.742577722678, 8590.441621294658, 8602.78493037676, 8403.36974726898, 8459.873101903477, 8479.2792612628, 8487.702930397407, 8498.0, 38390.61609383898, 38547.80726096351, 38559.22881542381, 38595.0, 38603.80698096492, 3610.891673249807, 3628.89113326598, 3660.890173294805, 3667.7615954962844, 3670.1556293872195, 31239.843800781076, 31454.213644658837, 31545.84227078873, 31570.526442103386, 31575.52636710428, 3090.3046814466566, 3115.8909438169553, 3124.297033167542, 3134.984325078383, 3135.9372812543847, 252500.0, 257881.0, 284849.1515084843, 298056.5485862849, 298642.0, 38807.61192388096, 49332.26001609979, 49389.75305123451, 49479.0, 49609.0, 51272.48727512752, 51311.48688513067, 51460.74269628703, 51472.970540588976, 51495.74252128715, 13493.932530337286, 13517.797233041512, 13652.522161724291, 13656.52202172939, 13665.658358541115, 10955.068819150301, 10987.121030317556, 11019.724506887393, 11035.889641103486, 11418.600348987746, 11287.661370158909, 13781.586552403443, 13781.793273100911, 13794.517191898436, 13798.310084495935, 5508.669479831223, 5510.311211098613, 5524.281843360418, 5558.610897237155, 5579.637323574036, 893.8033632600817, 990.9653162139291, 1022.1209759606733, 1026.9127124194379, 1039.4127318065268, 18334.81665183358, 20339.0, 24411.023559057794, 24613.0, 24721.381965451008, 1059.1262208677717, 1083.1172594335749, 1132.5696235430485, 1159.721666799962, 1175.3476820364608, 11147.219694621299, 12424.192427492364, 12452.937735311265, 12552.372381380894, 12989.480420783062, 306365.0, 306776.46611767245, 308919.0, 309212.0, 310116.0, 44781.0, 55188.72405637946, 55840.441595584336, 55846.0, 56122.0, 1376.3393571085735, 1496.880249580031, 1497.5282785922618, 1546.4587394411865, 1566.8119825621, 20855.270065547633, 21142.78857211408, 24181.879090604434, 24440.022399103837, 24443.38891527726, 38323.425148622235, 38384.23231535409, 38397.61602384052, 38399.0400239985, 38412.0, 277374.0, 277532.0, 277678.0, 278119.0, 278489.0, 34104.65895341114, 34896.127596810285, 34897.953061408196, 35218.64781352154, 35619.0, 44031.458898938385, 45339.77330113328, 45366.0, 45400.09199816051, 45733.856653582596, 24164.63753043741, 30362.84818575893, 30731.078067658007, 31480.527792083594, 32046.0, 30417.695823041486, 30799.846000769852, 31159.84420077885, 31358.0, 31466.0, 37123.44314835226, 37530.0, 37571.436428453046, 37881.67414140492, 38372.27324770448, 31762.52356214705, 31765.20586985344, 32208.35583288368, 32239.677603223667, 32251.35497290088, 23820.761792381854, 24391.878040609685, 24785.50428991446, 24862.37844053913, 25096.0], "value": 17526.875166004585, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.6063936063936, 434.92823684092144, 435.677598577053, 437.5602519467934, 452.65824302651515, 19797.109130089128, 21343.039563219652, 21939.561208775835, 22016.77983220167, 22263.332100037023, 22209.666854997187, 22237.2216972406, 22337.0, 22750.203742869002, 23566.646500302508, 5930.822075337746, 6125.754969801224, 6144.6006009609455, 6147.47746441553, 6153.876922461548, 5230.607704422184, 6002.96998515009, 6134.04922237054, 6139.815805525819, 6142.0, 5854.473097421232, 6050.727717252712, 6085.208922840025, 6085.604435711687, 6113.144159817607, 5876.0, 5987.1019347097845, 5989.730462129215, 6016.42843929826, 6027.276726792769, 5206.5314121729225, 5290.39160496544, 5312.840614781563, 5342.813001544927, 5355.705436201008, 26904.865475672694, 27149.049783257688, 27187.86406067957, 27308.726912730817, 27423.86288068567, 2108.7153234313323, 2159.514109325405, 2171.913123475059, 2172.3157205480225, 2175.3365223606793, 17643.294268229256, 17851.285948562043, 17976.280948762032, 18131.0, 18208.90895545514, 2128.9893550532147, 2170.696102545645, 2191.7808219178114, 2195.769444208367, 2197.8681279123143, 146954.26522867425, 147098.94552370318, 147808.0, 147948.0, 149945.99999999892, 12198.0, 12685.0, 12767.233666068347, 12779.552715654907, 12811.975041996619, 13552.254625995665, 13722.833559147384, 16536.917315413346, 16561.00633961966, 16583.419580314632, 8781.780455488537, 8978.416402933788, 9000.8649870252, 9030.774230644154, 9150.0, 7586.962065189749, 8206.810012548147, 8553.44402613828, 8562.48625082497, 8732.956335218283, 6225.439710426083, 6231.158793562901, 7396.445266604918, 7757.301842834172, 7893.684252629945, 2150.5161338698667, 2158.8920553972553, 2166.1876796201423, 2169.9240526581493, 2204.691343211952, 820.364217731263, 975.1564896364594, 983.3902980152209, 998.961080476311, 1037.305005646219, 20709.58580828375, 21234.57530849405, 21397.57204855894, 21473.785262147492, 21739.23912663049, 949.9002604726543, 1016.8627235323285, 1021.1371391174446, 1032.8347464405658, 1034.234666346898, 12797.936010319889, 13686.931565342109, 13868.445262189602, 13980.671836175547, 14014.948878834128, 524969.0, 533060.0, 537096.0, 575253.0, 577841.0, 113722.43138784253, 119935.40032299783, 134709.3264533671, 139871.0, 140081.5991840089, 1128.2046157459135, 1178.5757127434117, 1225.6384366611871, 1248.793948998405, 1254.2315335377075, 18650.53373665615, 20872.47818804542, 21958.670619941033, 22182.66725999079, 22250.0, 45021.774891126646, 55071.0, 55284.72357638186, 56154.0, 57988.13017804821, 77601.6278453546, 78660.82008769987, 78927.60536197282, 80000.0, 80421.59789201016, 8479.32165426752, 8656.22094011542, 8780.868286975829, 9097.727068187965, 9132.178103970675, 12792.488300468249, 13712.93143534276, 13713.245771482665, 14035.789463158266, 14066.788998164831, 9773.657921972697, 11748.353840538852, 11876.287422754662, 12035.0, 12052.638420847388, 9382.765430864283, 9482.288828337765, 10228.28402011852, 11027.558897644005, 11033.668989930315, 11873.525058997544, 12026.75946481083, 12066.698332541757, 12112.576059837864, 12209.145359824726, 8889.777755556162, 8898.688545900863, 8986.86519702192, 9021.50381728985, 9022.774430639023, 11612.0, 11708.238964467282, 11779.998700110587, 11820.645380638594, 11903.988161006411], "value": 10421.358793522912, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1211.7515909238605, 1317.8220940173073, 1438.3743071763784, 1459.7664973097735, 1461.1306272767706, 11316.377599232039, 12180.390980450975, 12183.0, 12192.329421881794, 12461.875381246185, 12143.271403715784, 12290.38548072595, 12313.322767247797, 12323.075769317293, 12361.19652222605, 2599.3111825366245, 2637.3406648337914, 2639.934001649955, 2641.3660721426913, 2643.7224091470407, 2319.7100362454694, 2493.6758221431187, 2540.9745902541017, 2585.6250843627663, 2589.2361753282735, 2521.9747802522015, 2530.7975361971, 2570.3959569501153, 2570.8586027768465, 2574.703909050455, 2547.694276686791, 2570.0362364113457, 2597.1559243245833, 2601.0636170978432, 2601.7398260174023, 2419.7459266777087, 2551.783098436635, 2567.101514469939, 2588.1459118491043, 2599.129291687287, 11875.049996000385, 11976.0, 12071.93964030183, 12072.939635301855, 12087.879121208764, 6635.834104147387, 6820.931790682129, 6865.897011544831, 6869.519133660646, 6878.243393226791, 3788.886333410002, 4073.97963010186, 4338.089001309729, 4405.559444055601, 4427.822887084512, 1227.2391117507113, 1227.9324637144953, 1256.4534427524056, 1263.434404818497, 1283.3198404845448, 12833.550825721055, 16694.66610667792, 16813.327466901308, 16865.494035178963, 16900.915495422567, 4708.764561771898, 5422.5119739223655, 5447.264619276426, 5619.157126430986, 5712.7714891403875, 11860.525578976916, 11933.343666098448, 11990.220635658654, 11993.520259189534, 12004.819927701092, 6290.339514350927, 6330.746770129235, 6335.239771227482, 6341.778037768656, 6346.746130154835, 5298.0, 5843.503302219274, 5931.3178984417345, 6018.729157187937, 6286.717097730613, 4313.827446902089, 4434.312681534368, 4442.0, 4456.398386217884, 4471.306947423154, 1295.8898493628146, 1298.8246586710861, 1304.934753262333, 1312.297920612487, 1314.4413624209672, 793.1750978981794, 793.1830214878685, 815.9143289954469, 822.7737372222567, 840.033960944906, 6122.938770612326, 6174.722137503823, 6337.809865704036, 6403.199600049994, 6405.615663060231, 535.9169328754049, 547.6659237864899, 592.937741537141, 598.5929567893787, 609.0, 4224.260754367973, 4348.891277718019, 4360.215161271001, 4497.527759585262, 4748.335233067373, 14961.251937403304, 15080.472183473745, 15087.547373578811, 15225.695486090437, 15421.691566168614, 14864.256787160599, 14956.0278581894, 15473.071615703095, 15536.223188840739, 15571.221438928009, 8543.487390756574, 8678.95660521706, 8825.0, 8832.735017949472, 8842.823143537093, 6705.530612857053, 6721.865562688817, 7017.614031228127, 7051.153861536648, 7081.044059052168, 13446.327683615977, 13639.79540306916, 13650.453981840616, 13655.04414690962, 13787.379567919668, 13774.035817492679, 13943.30283485842, 14036.508722194674, 14112.647183820487, 14407.927960360132, 7939.245771651684, 9340.81318373615, 9362.95318523403, 9378.343515954091, 9462.2430205582, 10559.788804224027, 10741.462926853783, 10747.355158690503, 10750.569977200825, 10768.300060496043, 8991.820163596822, 9210.447373157363, 9330.066993300616, 9348.813023739624, 9377.765555861159, 9014.819703606023, 9039.186473217442, 9040.502772347321, 9117.270618350649, 9120.5439728012, 6877.621730804653, 7941.523508589503, 8094.87857682147, 8110.918890811016, 8118.512889226666, 6002.729877155626, 6020.277566692025, 6100.877982440237, 6175.876482470236, 6203.937960620336, 4531.524189960139, 5935.605132793704, 6059.697015149137, 6099.268087829489, 6194.14175747283], "value": 5354.453759862372, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.1800731307024, 396.7500474700938, 400.1616613195355, 405.60250954065026, 405.64303412996543, 4747.0, 5747.626404283724, 5932.614380065298, 6125.846853828656, 6306.274778400485, 6020.819375418733, 6133.693315334238, 6145.692715364236, 6156.107364432155, 6175.197224360827, 3851.9407163030182, 4273.465816772903, 4527.800132964767, 4537.841175558856, 4551.635869130479, 3736.719746019046, 4492.97753511232, 4498.482674492426, 4510.774461276939, 4560.33875088112, 4488.124815660947, 4493.370928070073, 4494.303382975644, 4511.35335602506, 4522.706024108423, 3527.488514165457, 4031.475908131924, 4369.628935457495, 4491.573300536444, 4524.00471896184, 3166.4458719723957, 3609.675129238381, 3830.655241028293, 3831.4252862070634, 3852.8844134676006, 5702.344230413514, 6024.397560243984, 6041.6375017499095, 6044.335123136496, 6058.515318774532, 5206.479352064801, 5298.655587386807, 5317.255584218213, 5333.4399888011585, 5356.625036247464, 6518.902216466757, 6559.2128944526485, 6569.0, 6573.769918052893, 6601.438877695402, 4232.386303985935, 4322.8703138905885, 4330.891727706832, 4339.045410009793, 4342.891427714301, 5742.368339482654, 6672.766453174116, 6829.453643708492, 6886.380225779704, 6992.825179370506, 3523.3481805865486, 4127.628513433745, 4224.535301116936, 4413.0070734084575, 4417.185268057769, 3685.447182922583, 4322.69741118125, 4480.395146655225, 4491.14668213045, 4491.371208030879, 4427.667924905645, 4464.192002239099, 5163.948360516422, 5249.658772179872, 5328.229320753187, 6633.402993730588, 6648.434883034899, 6648.902931016425, 6661.433778128817, 6671.33286671329, 1278.622806272152, 1358.531306699187, 1493.7759336099455, 1498.0786816108016, 1528.7477566201674, 945.239082538563, 996.6296342529023, 997.6308765756717, 1015.263933648114, 1015.6546774096843, 5702.54379649627, 6118.388161183935, 6294.622322660563, 6310.400511951358, 6324.525660575475, 5945.970270148621, 6116.715489747114, 6229.595076320024, 6815.352541508549, 6822.317768223138, 5004.84985450437, 5132.203728694927, 5314.2825718528275, 6300.361176746877, 6391.520635952322, 3140.450421176285, 3144.1039303798307, 3165.920851978719, 3595.6044835068124, 3598.226381328027, 6613.875641141018, 6884.827879303058, 7099.680514376865, 7107.0, 7119.857602847914, 6140.907886381708, 6156.538259630456, 7020.157581090302, 7040.155181378165, 7082.008518807371, 5821.446962538552, 6898.964805382324, 6983.511154219156, 7009.544379615309, 7080.0795896534155, 6367.554271200971, 6851.143607049119, 6884.347756538295, 6918.7232510699005, 6940.640182338029, 6532.673366331569, 6676.6297964222, 6699.698513566998, 6710.865782684416, 6739.764108256188, 6592.373724496165, 6758.78341898463, 6925.89611155823, 7122.643867806485, 7170.103737032871, 4128.752274863517, 4142.647874930544, 4396.51638319791, 4402.449693788277, 4412.338149277634, 5666.291713535808, 5997.260794369584, 6387.648679322682, 6958.930410695828, 7209.783706488814, 5430.5384042356845, 5696.0, 6546.116274302908, 6680.799576012728, 6695.363940425652, 5287.471252874681, 6126.877462450815, 6584.539082264196, 6657.500687448364, 6679.198496180489, 5336.0, 6294.71673774672, 6511.66510865281, 6546.672666366758, 6586.497131090155, 6475.0, 6633.70148343316, 6668.933310666831, 6699.0621313015245, 6699.129113215251, 5678.3753787084215, 6337.15587165869, 6437.742490300336, 6492.740290388331, 6546.901796472961], "value": 4720.477348554757, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.2631794523422, 811.9188081191885, 816.9264766171043, 848.6053984897021, 853.9581710313417, 14384.280785960686, 14733.852661473382, 14869.628259293522, 16540.91729541352, 16945.661086778273, 14435.783463248059, 16216.594585135377, 16342.264598093085, 16408.0, 17629.0, 977.4330888084894, 982.3025651787204, 996.1582462818907, 1002.6240159940022, 1006.9879770830305, 996.1681995533702, 999.5851721535554, 1000.309786247487, 1004.4575928998332, 1005.1757558801786, 1040.0795296162912, 1047.9109275711573, 1064.7125276175427, 1065.1425602390073, 1066.568039943824, 1033.152814691953, 1054.104011590146, 1057.9523921423554, 1059.0680201422776, 1062.0229388962173, 766.5132640773094, 775.9573223472763, 778.5289899610767, 779.9181085985947, 782.9334506566948, 14103.435862565486, 14355.425782968668, 16599.41902033423, 17395.913020434942, 17408.30366785327, 16733.24700388473, 17110.058946758047, 17573.0, 17671.911640441846, 17746.20142093609, 14325.57023289303, 14784.334704938303, 14825.036372635745, 14906.925465372713, 14910.925445372812, 3174.158847905307, 3237.838108094609, 3379.357921994838, 3383.204946837491, 3603.513525674027, 105528.47235763849, 105799.0, 115472.42263788711, 115848.84151158464, 116944.83055169425, 13337.866621333857, 13523.0, 13607.727845443036, 13659.658508537168, 13665.590032299047, 19778.901105494668, 25785.226443206735, 25941.870290648425, 25956.0915367965, 26365.20904372872, 6873.381395674413, 7215.025971493887, 7259.6007219603425, 7279.526830755984, 7282.96358518204, 3342.5320455136302, 3860.9613903861164, 3939.546952100488, 3942.42834788954, 3951.249262640089, 4151.0037590977945, 4228.344606585984, 4292.613664770186, 4408.735475871458, 4445.844395446144, 907.945523268606, 925.0656836595087, 941.6468824190929, 942.886853577575, 958.5830163878735, 628.9716962736687, 639.8496353356958, 647.2071712152575, 657.4707360574688, 679.0154276299386, 10946.835797463045, 12084.456199471046, 12112.333821639719, 12170.14808963364, 12300.876991230152, 643.1446176585131, 649.1074772188241, 650.1580453312877, 655.1908393134476, 668.7024274197935, 4157.5010998679745, 5509.862253443696, 5579.721013949367, 5687.7156142192725, 5783.913241301384, 87286.1271387278, 88432.55783721169, 90474.09525904788, 93764.0, 114192.0, 81396.18603813885, 83333.3333333342, 90514.99999999868, 92927.0, 94931.0, 84970.0, 85301.57349213214, 85658.0, 85824.57087714647, 88434.0, 11363.37501437429, 12092.0, 12121.969632580976, 12677.17598356104, 12812.679683008, 28853.42293154167, 33027.33945321128, 36275.0, 39881.00297492586, 40532.960027998604, 54490.0, 67079.98760037207, 68567.65716171387, 70027.29972700411, 70970.29029709636, 16019.599510012344, 16735.832641673915, 17014.0, 17082.572935676708, 17451.04019278952, 17808.287668493114, 18605.348812791486, 18683.439496815117, 18704.812951870306, 18751.249950001846, 15795.21023948821, 16324.510264692079, 16562.254698538836, 16940.491785246464, 16984.15079246008, 17183.828161718724, 17353.652926941137, 17638.02990835465, 17692.699949006546, 17718.82281177172, 16905.323787048874, 17365.305387784854, 17427.302907883543, 17567.0, 17591.384301549384, 11704.356260405759, 11847.76304473923, 12108.75782484363, 12148.21036632616, 12560.560380386643, 13274.004449666121, 13450.663733406353, 13990.720185596434, 14022.368993395117, 14217.928910355382], "value": 8345.933206029735, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1508.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 107.0, 110.0, 148.0, 108.0, 111.0, 116.0, 118.0, 138.0, 113.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 197.0, 203.0, 207.0, 210.0, 212.0, 209.0, 199.0, 200.0], "value": 202.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 82.0, 63.0, 62.0, 89.0, 85.0, 93.0, 64.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 82.0, 67.0, 67.0, 68.0, 82.0, 70.0, 68.0, 90.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 92.0, 88.0, 89.0, 88.0, 88.0, 90.0, 89.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 144.0, 96.0, 105.0, 97.0, 96.0, 118.0, 156.0], "value": 110.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 91.0, 91.0, 89.0, 96.0, 107.0, 92.0, 90.0, 90.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 48.0, 49.0, 51.0, 50.0, 51.0, 50.0, 53.0, 50.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 69.0, 93.0, 82.0, 58.0, 58.0, 56.0, 56.0, 55.0, 55.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 81.0, 83.0, 82.0, 84.0, 82.0, 84.0, 82.0, 86.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 148.0, 128.0, 137.0, 140.0, 140.0, 146.0, 160.0, 225.0, 151.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [231.0, 238.0, 187.0, 186.0, 184.0, 183.0, 187.0, 195.0, 230.0, 183.0], "value": 200.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 67.0, 62.0, 62.0, 83.0, 100.0, 71.0, 90.0, 97.0], "value": 77.2, "unit": "score"}]}]} 07:22:04 INFO - Return code: 0 07:22:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:22:04 INFO - # TBPL SUCCESS # 07:22:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:22:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:22:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:22:04 INFO - 2017-05-12 07:22:04.455 screenresolution[1666:11065] starting screenresolution argv=screenresolution get 07:22:04 INFO - 2017-05-12 07:22:04.458 screenresolution[1666:11065] Display 0: 1600x1200x32@60 07:22:04 INFO - 2017-05-12 07:22:04.464 screenresolution[1667:11068] starting screenresolution argv=screenresolution list 07:22:04 INFO - Available Modes on Display 0 07:22:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:22:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:22:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:22:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:22:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:22:04 INFO - Intel Iris: 07:22:04 INFO - Chipset Model: Intel Iris 07:22:04 INFO - Type: GPU 07:22:04 INFO - Bus: Built-In 07:22:04 INFO - VRAM (Dynamic, Max): 1536 MB 07:22:04 INFO - Vendor: Intel (0x8086) 07:22:04 INFO - Device ID: 0x0a2e 07:22:04 INFO - Revision ID: 0x0009 07:22:04 INFO - Displays: 07:22:04 INFO - Display: 07:22:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:22:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:22:04 INFO - Display Serial Number: 1600x1200 60 07:22:04 INFO - Main Display: Yes 07:22:04 INFO - Mirror: Off 07:22:04 INFO - Online: Yes 07:22:04 INFO - Rotation: Supported 07:22:04 INFO - Return code: 0 07:22:04 INFO - Running post-action listener: _resource_record_post_action 07:22:04 INFO - [mozharness: 2017-05-12 14:22:04.705130Z] Finished run-tests step (success) 07:22:04 INFO - Running post-run listener: _resource_record_post_run 07:22:04 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 39390208; Write bytes: 529679360; Read time: 255; Write time: 1644 07:22:04 INFO - TinderboxPrint: CPU usage
54.0% 07:22:04 INFO - TinderboxPrint: I/O read bytes / time
39,390,208 / 255 07:22:04 INFO - TinderboxPrint: I/O write bytes / time
529,679,360 / 1,644 07:22:04 INFO - TinderboxPrint: CPU idle
2,199.3 (46.0%) 07:22:04 INFO - TinderboxPrint: CPU system
957.8 (20.0%) 07:22:04 INFO - TinderboxPrint: CPU user
1,624.5 (34.0%) 07:22:04 INFO - TinderboxPrint: Swap in / out
253,853,696 / 0 07:22:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 215478784; Write bytes: 213399552; Read time: 16605; Write time: 448 07:22:04 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 35286016; Write bytes: 310307840; Read time: 227; Write time: 1175 07:22:05 INFO - Running post-run listener: _upload_blobber_files 07:22:05 INFO - Blob upload gear active. 07:22:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:22:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:22:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:22:05 INFO - (blobuploader) - INFO - Open directory for files ... 07:22:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:22:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:22:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:06 INFO - (blobuploader) - INFO - Done attempting. 07:22:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:22:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:22:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:07 INFO - (blobuploader) - INFO - Done attempting. 07:22:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:22:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:22:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:08 INFO - (blobuploader) - INFO - Done attempting. 07:22:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:22:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:10 INFO - (blobuploader) - INFO - Done attempting. 07:22:10 INFO - (blobuploader) - INFO - Iteration through files over. 07:22:10 INFO - Return code: 0 07:22:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:22:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:22:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c000744c0ae3c1cdb194b8e66a8ce994a236ce941c96baccaae7890d60b415f936237eee7a25beccd2cf193dc008c51c36002764068c118480cc8788d9262b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f104a430808e24fd163e505525e0dc34ebe2945708561985d06ecf42e37e9231ecec5c248a607e784ee7eba170dc6255099b8571c06ce72e6d61212070a053d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d48f6858bbbcc4a3d9507e576d0acdffd13b957ffe842bcf8afedb9c965df51a1d6cb5ae03f1e21de927be61d90c18cc4e0d2e83ab41a6f572906b3a8354c30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a6d0aafc1e692623db864d71e5e1f97a4f1f55f6f884f72b8f2f39fc715a29d93ae051e57dd11ed6301adca92786cb5e2a68055bd187634bc8518604cabbd7"} 07:22:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:22:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:22:10 INFO - Contents: 07:22:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c000744c0ae3c1cdb194b8e66a8ce994a236ce941c96baccaae7890d60b415f936237eee7a25beccd2cf193dc008c51c36002764068c118480cc8788d9262b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f104a430808e24fd163e505525e0dc34ebe2945708561985d06ecf42e37e9231ecec5c248a607e784ee7eba170dc6255099b8571c06ce72e6d61212070a053d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d48f6858bbbcc4a3d9507e576d0acdffd13b957ffe842bcf8afedb9c965df51a1d6cb5ae03f1e21de927be61d90c18cc4e0d2e83ab41a6f572906b3a8354c30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a6d0aafc1e692623db864d71e5e1f97a4f1f55f6f884f72b8f2f39fc715a29d93ae051e57dd11ed6301adca92786cb5e2a68055bd187634bc8518604cabbd7"} 07:22:10 INFO - Running post-run listener: copy_logs_to_upload_dir 07:22:10 INFO - Copying logs to upload dir... 07:22:10 INFO - mkdir: /builds/slave/test/build/upload/logs 07:22:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.448299 ========= master_lag: 2.70 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-12 07:22:13.037562) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:22:13.041352) ========= 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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/7c000744c0ae3c1cdb194b8e66a8ce994a236ce941c96baccaae7890d60b415f936237eee7a25beccd2cf193dc008c51c36002764068c118480cc8788d9262b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f104a430808e24fd163e505525e0dc34ebe2945708561985d06ecf42e37e9231ecec5c248a607e784ee7eba170dc6255099b8571c06ce72e6d61212070a053d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d48f6858bbbcc4a3d9507e576d0acdffd13b957ffe842bcf8afedb9c965df51a1d6cb5ae03f1e21de927be61d90c18cc4e0d2e83ab41a6f572906b3a8354c30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a6d0aafc1e692623db864d71e5e1f97a4f1f55f6f884f72b8f2f39fc715a29d93ae051e57dd11ed6301adca92786cb5e2a68055bd187634bc8518604cabbd7"} build_url:https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010882 build_url: 'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/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/7c000744c0ae3c1cdb194b8e66a8ce994a236ce941c96baccaae7890d60b415f936237eee7a25beccd2cf193dc008c51c36002764068c118480cc8788d9262b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f104a430808e24fd163e505525e0dc34ebe2945708561985d06ecf42e37e9231ecec5c248a607e784ee7eba170dc6255099b8571c06ce72e6d61212070a053d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d48f6858bbbcc4a3d9507e576d0acdffd13b957ffe842bcf8afedb9c965df51a1d6cb5ae03f1e21de927be61d90c18cc4e0d2e83ab41a6f572906b3a8354c30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a6d0aafc1e692623db864d71e5e1f97a4f1f55f6f884f72b8f2f39fc715a29d93ae051e57dd11ed6301adca92786cb5e2a68055bd187634bc8518604cabbd7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:22:13.074464) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:22:13.074788) ========= 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.Vy89CkFRpa/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.HtRoYWkz8r/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005075 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:22:13.122736) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:22:13.123056) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:22:13.123405) ========= ========= Total master_lag: 2.83 =========