builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0234 starttime: 1494597798.7 results: success (0) revision: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.698579) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.699078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.699420) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.725534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.725944) ========= 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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:03:18-- 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% 67.5M=0s 2017-05-12 07:03:18 (67.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.235492 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.985700) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:18.986152) ========= 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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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.026183 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:19.035102) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 07:03:19.035448) ========= 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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:03:19,141 truncating revision to first 12 chars 2017-05-12 07:03:19,141 Setting DEBUG logging. 2017-05-12 07:03:19,141 attempt 1/10 2017-05-12 07:03:19,142 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:03:20,153 unpacking tar archive at: mozilla-central-030c0a7c8781/testing/mozharness/ program finished with exit code 0 elapsedTime=1.280630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-12 07:03:20.338419) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:20.338801) ========= script_repo_revision: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:20.339264) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:20.339672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-12 07:03:20.360592) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-12 07:03:20.361270) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:03:20 INFO - MultiFileLogger online at 20170512 07:03:20 in /builds/slave/test 07:03:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:03:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:03:20 INFO - {'append_to_log': False, 07:03:20 INFO - 'base_work_dir': '/builds/slave/test', 07:03:20 INFO - 'blob_upload_branch': 'Firefox', 07:03:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:03:20 INFO - 'branch': 'Firefox', 07:03:20 INFO - 'buildbot_json_path': 'buildprops.json', 07:03:20 INFO - 'config_files': ('talos/mac_config.py',), 07:03:20 INFO - 'default_actions': ('clobber', 07:03:20 INFO - 'read-buildbot-config', 07:03:20 INFO - 'download-and-extract', 07:03:20 INFO - 'populate-webroot', 07:03:20 INFO - 'create-virtualenv', 07:03:20 INFO - 'install', 07:03:20 INFO - 'run-tests'), 07:03:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:03:20 INFO - 'download_minidump_stackwalk': True, 07:03:20 INFO - 'download_symbols': 'ondemand', 07:03:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:03:20 INFO - 'tooltool.py': '/tools/tooltool.py', 07:03:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:03:20 INFO - '/tools/misc-python/virtualenv.py')}, 07:03:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:03:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:03:20 INFO - 'gecko_profile': False, 07:03:20 INFO - 'gecko_profile_interval': 0, 07:03:20 INFO - 'installer_path': 'installer.exe', 07:03:20 INFO - 'log_level': 'info', 07:03:20 INFO - 'log_name': 'talos', 07:03:20 INFO - 'log_to_console': True, 07:03:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:03:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:03:20 INFO - 'opt_config_files': (), 07:03:20 INFO - 'pip_index': False, 07:03:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:03:20 INFO - 'cmd': ('bash', 07:03:20 INFO - '-c', 07:03:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:03:20 INFO - 'enabled': True, 07:03:20 INFO - 'halt_on_failure': False, 07:03:20 INFO - 'name': 'check_screen_resolution'},), 07:03:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:03:20 INFO - 'cmd': ('bash', 07:03:20 INFO - '-c', 07:03:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:03:20 INFO - 'enabled': True, 07:03:20 INFO - 'halt_on_failure': False, 07:03:20 INFO - 'name': 'check_screen_resolution'},), 07:03:20 INFO - 'run_cmd_checks_enabled': True, 07:03:20 INFO - 'suite': 'dromaeojs', 07:03:20 INFO - 'system_bits': '32', 07:03:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:03:20 INFO - 'title': 't-yosemite-r7-0234', 07:03:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:03:20 INFO - 'use_talos_json': True, 07:03:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:03:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:03:20 INFO - 'work_dir': 'build'} 07:03:20 INFO - [mozharness: 2017-05-12 14:03:20.572735Z] Running clobber step. 07:03:20 INFO - Running pre-action listener: _resource_record_pre_action 07:03:20 INFO - Running main action method: clobber 07:03:20 INFO - rmtree: /builds/slave/test/build 07:03:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:03:22 INFO - Running post-action listener: _resource_record_post_action 07:03:22 INFO - [mozharness: 2017-05-12 14:03:22.970276Z] Finished clobber step (success) 07:03:22 INFO - [mozharness: 2017-05-12 14:03:22.970410Z] Running read-buildbot-config step. 07:03:22 INFO - Running pre-action listener: _resource_record_pre_action 07:03:22 INFO - Running main action method: read_buildbot_config 07:03:22 INFO - Using buildbot properties: 07:03:22 INFO - { 07:03:22 INFO - "project": "", 07:03:22 INFO - "product": "firefox", 07:03:22 INFO - "who": "cbook@mozilla.com", 07:03:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:03:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:03:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:03:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:03:22 INFO - "stage_platform": "macosx64", 07:03:22 INFO - "basedir": "/builds/slave/test", 07:03:22 INFO - "buildnumber": 95, 07:03:22 INFO - "platform": "yosemite_r7", 07:03:22 INFO - "parent_task_id": "RhC-X0LeQPWVeQaPIwCr_g", 07:03:22 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:03:22 INFO - "slavebuilddir": "test", 07:03:22 INFO - "taskId": "b35ewPdPSSKgMcGUsRgphw", 07:03:22 INFO - "branch": "mozilla-central", 07:03:22 INFO - "script_repo_revision": "production", 07:03:22 INFO - "revision": "030c0a7c878192d8dc1b04c182575c2f7858cdd0", 07:03:22 INFO - "slavename": "t-yosemite-r7-0234", 07:03:22 INFO - "repo_path": "mozilla-central" 07:03:22 INFO - } 07:03:22 INFO - Finding installer, test and symbols from parent task. 07:03:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b35ewPdPSSKgMcGUsRgphw'}, attempt #1 07:03:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b35ewPdPSSKgMcGUsRgphw'}, attempt #1 07:03:24 INFO - Task dependencies: M2ZmG5JhReGt6parYpoB8Q 07:03:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g'}, attempt #1 07:03:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:03:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/test_packages.json 07:03:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/target.crashreporter-symbols.zip 07:03:24 INFO - Running post-action listener: _resource_record_post_action 07:03:24 INFO - [mozharness: 2017-05-12 14:03:24.675848Z] Finished read-buildbot-config step (success) 07:03:24 INFO - [mozharness: 2017-05-12 14:03:24.675966Z] Running download-and-extract step. 07:03:24 INFO - Running pre-action listener: _resource_record_pre_action 07:03:24 INFO - Running main action method: download_and_extract 07:03:24 INFO - mkdir: /builds/slave/test/build/tests 07:03:24 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:03:24 INFO - trying https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:03:24 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:03:24 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:03:25 INFO - Downloaded 1325 bytes. 07:03:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:03:25 INFO - Using the following test package requirements: 07:03:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:03:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:03:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:03:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:03:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'jsshell-mac.zip'], 07:03:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:03:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:03:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:03:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:03:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:03:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:03:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:03:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:03:25 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:03:25 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:03:25 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:03:26 INFO - Content-Length response header: 56563765 07:03:26 INFO - Bytes received: 56563765 07:03:44 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:03:44 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:03:44 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:03:44 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:03:46 INFO - Content-Length response header: 56563765 07:03:46 INFO - Bytes received: 56563765 07:04:02 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:04:02 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:04:02 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:04:04 INFO - Content-Length response header: 14409411 07:04:04 INFO - Bytes received: 14409411 07:04:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:04 INFO - trying https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:04:04 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:04:04 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:04:06 INFO - Downloaded 75933392 bytes. 07:04:06 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:04:06 INFO - mkdir: /builds/slave/test/properties 07:04:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:04:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:04:06 INFO - Contents: 07:04:06 INFO - build_url:https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:04:06 INFO - Running post-action listener: _resource_record_post_action 07:04:06 INFO - Running post-action listener: set_extra_try_arguments 07:04:06 INFO - [mozharness: 2017-05-12 14:04:06.698691Z] Finished download-and-extract step (success) 07:04:06 INFO - [mozharness: 2017-05-12 14:04:06.698811Z] Running populate-webroot step. 07:04:06 INFO - Running pre-action listener: _resource_record_pre_action 07:04:06 INFO - Running main action method: populate_webroot 07:04:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:04:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:04:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:04:06 INFO - '--tppagecycles', 07:04:06 INFO - '7'], 07:04:06 INFO - 'tests': ['tsvgm']}}, 07:04:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:04:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:04:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:04:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:04:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:04:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:04:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['damp', 'tps']}, 07:04:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'tests': ['damp', 'tps']}, 07:04:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['dromaeo_dom']}, 07:04:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:04:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:04:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:04:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['a11yr', 07:04:06 INFO - 'ts_paint', 07:04:06 INFO - 'tpaint', 07:04:06 INFO - 'sessionrestore', 07:04:06 INFO - 'sessionrestore_no_auto_restore', 07:04:06 INFO - 'tabpaint']}, 07:04:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:04:06 INFO - 'ts_paint', 07:04:06 INFO - 'tpaint', 07:04:06 INFO - 'sessionrestore', 07:04:06 INFO - 'sessionrestore_no_auto_restore', 07:04:06 INFO - 'tabpaint']}, 07:04:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:04:06 INFO - 'ts_paint', 07:04:06 INFO - 'tpaint', 07:04:06 INFO - 'sessionrestore', 07:04:06 INFO - 'sessionrestore_no_auto_restore', 07:04:06 INFO - 'tabpaint']}, 07:04:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:04:06 INFO - 'ts_paint', 07:04:06 INFO - 'tpaint', 07:04:06 INFO - 'sessionrestore', 07:04:06 INFO - 'sessionrestore_no_auto_restore', 07:04:06 INFO - 'tabpaint']}, 07:04:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['a11yr', 07:04:06 INFO - 'ts_paint', 07:04:06 INFO - 'tpaint', 07:04:06 INFO - 'sessionrestore', 07:04:06 INFO - 'sessionrestore_no_auto_restore', 07:04:06 INFO - 'tabpaint']}, 07:04:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['a11yr', 07:04:06 INFO - 'ts_paint', 07:04:06 INFO - 'tpaint', 07:04:06 INFO - 'sessionrestore', 07:04:06 INFO - 'sessionrestore_no_auto_restore', 07:04:06 INFO - 'tabpaint']}, 07:04:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:04:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:04:06 INFO - 'bloom_basic_ref']}, 07:04:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['tsvgx', 07:04:06 INFO - 'tsvgr_opacity', 07:04:06 INFO - 'tart', 07:04:06 INFO - 'tscrollx', 07:04:06 INFO - 'cart', 07:04:06 INFO - 'tsvg_static']}, 07:04:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:04:06 INFO - 'tsvgr_opacity', 07:04:06 INFO - 'tart', 07:04:06 INFO - 'tscrollx', 07:04:06 INFO - 'cart', 07:04:06 INFO - 'tsvg_static']}, 07:04:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'talos_options': ['--disable-e10s'], 07:04:06 INFO - 'tests': ['tp5o']}, 07:04:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:04:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'talos_options': ['--disable-e10s', 07:04:06 INFO - '--xperf_path', 07:04:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:04:06 INFO - 'tests': ['tp5n']}, 07:04:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:04:06 INFO - 'talos_options': ['--xperf_path', 07:04:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:04:06 INFO - 'tests': ['tp5n']}}, 07:04:06 INFO - 'talos.zip': {'path': '', 07:04:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:04:06 INFO - Running post-action listener: _resource_record_post_action 07:04:06 INFO - [mozharness: 2017-05-12 14:04:06.719620Z] Finished populate-webroot step (success) 07:04:06 INFO - [mozharness: 2017-05-12 14:04:06.719738Z] Running create-virtualenv step. 07:04:06 INFO - Running pre-action listener: _resource_record_pre_action 07:04:06 INFO - Running main action method: create_virtualenv 07:04:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:04:06 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:04:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:04:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:04:06 INFO - Using real prefix '/tools/python27' 07:04:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:04:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:04:10 INFO - Installing pip.................done. 07:04:10 INFO - Return code: 0 07:04:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:04:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:04:10 INFO - Reading from file tmpfile_stdout 07:04:10 INFO - Output received: 07:04:10 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:04:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:10 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:10 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:04:10 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:04:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:10 INFO - 'HOME': '/Users/cltbld', 07:04:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:10 INFO - 'LOGNAME': 'cltbld', 07:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:10 INFO - 'MOZ_NO_REMOTE': '1', 07:04:10 INFO - 'NO_EM_RESTART': '1', 07:04:10 INFO - 'PAGER': '/bin/cat', 07:04:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:10 INFO - 'PWD': '/builds/slave/test', 07:04:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:10 INFO - 'SHELL': '/bin/bash', 07:04:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:10 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:10 INFO - 'USER': 'cltbld', 07:04:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:10 INFO - 'XPC_FLAGS': '0x0', 07:04:10 INFO - 'XPC_SERVICE_NAME': '0', 07:04:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:10 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:04:10 INFO - Cleaning up... 07:04:10 INFO - Return code: 0 07:04:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:10 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:10 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:04:10 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:04:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:10 INFO - 'HOME': '/Users/cltbld', 07:04:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:10 INFO - 'LOGNAME': 'cltbld', 07:04:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:11 INFO - 'MOZ_NO_REMOTE': '1', 07:04:11 INFO - 'NO_EM_RESTART': '1', 07:04:11 INFO - 'PAGER': '/bin/cat', 07:04:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:11 INFO - 'PWD': '/builds/slave/test', 07:04:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:11 INFO - 'SHELL': '/bin/bash', 07:04:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:11 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:11 INFO - 'USER': 'cltbld', 07:04:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:11 INFO - 'XPC_FLAGS': '0x0', 07:04:11 INFO - 'XPC_SERVICE_NAME': '0', 07:04:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:11 INFO - Downloading/unpacking psutil>=3.1.1 07:04:11 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:04:11 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:04:11 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:04:11 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:04:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:04:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:04:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:04:13 INFO - Installing collected packages: psutil 07:04:13 INFO - Running setup.py install for psutil 07:04:13 INFO - building 'psutil._psutil_osx' extension 07:04:13 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:04:13 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:04:13 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:04:13 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:04:14 INFO - building 'psutil._psutil_posix' extension 07:04:14 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:04:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:04:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:04:14 INFO - ^ 07:04:14 INFO - 1 warning generated. 07:04:14 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:04:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:04:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:04:14 INFO - Successfully installed psutil 07:04:14 INFO - Cleaning up... 07:04:14 INFO - Return code: 0 07:04:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:04:14 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:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:14 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:04:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:14 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:14 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:04:14 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:04:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:14 INFO - 'HOME': '/Users/cltbld', 07:04:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:14 INFO - 'LOGNAME': 'cltbld', 07:04:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:14 INFO - 'MOZ_NO_REMOTE': '1', 07:04:14 INFO - 'NO_EM_RESTART': '1', 07:04:14 INFO - 'PAGER': '/bin/cat', 07:04:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:14 INFO - 'PWD': '/builds/slave/test', 07:04:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:14 INFO - 'SHELL': '/bin/bash', 07:04:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:14 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:14 INFO - 'USER': 'cltbld', 07:04:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:14 INFO - 'XPC_FLAGS': '0x0', 07:04:14 INFO - 'XPC_SERVICE_NAME': '0', 07:04:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:04:14 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:04:14 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:04:14 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:04:14 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:04:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:04:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:04:16 INFO - Installing collected packages: mozsystemmonitor 07:04:16 INFO - Running setup.py install for mozsystemmonitor 07:04:16 INFO - Successfully installed mozsystemmonitor 07:04:16 INFO - Cleaning up... 07:04:16 INFO - Return code: 0 07:04:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:04:16 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:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:16 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:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:16 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:16 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:04:16 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:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:16 INFO - 'HOME': '/Users/cltbld', 07:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:16 INFO - 'LOGNAME': 'cltbld', 07:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:16 INFO - 'MOZ_NO_REMOTE': '1', 07:04:16 INFO - 'NO_EM_RESTART': '1', 07:04:16 INFO - 'PAGER': '/bin/cat', 07:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:16 INFO - 'PWD': '/builds/slave/test', 07:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:16 INFO - 'SHELL': '/bin/bash', 07:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:16 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:16 INFO - 'USER': 'cltbld', 07:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:16 INFO - 'XPC_FLAGS': '0x0', 07:04:16 INFO - 'XPC_SERVICE_NAME': '0', 07:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:17 INFO - Downloading/unpacking jsonschema==2.5.1 07:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:04:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:04:18 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:04:18 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:04:18 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:04:18 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:04:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:04:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:04:18 INFO - warning: no files found matching '*.txt' 07:04:18 INFO - no previously-included directories found matching 'build' 07:04:18 INFO - no previously-included directories found matching 'dist' 07:04:18 INFO - no previously-included directories found matching '.git*' 07:04:19 INFO - Installing collected packages: jsonschema, functools32 07:04:19 INFO - Running setup.py install for functools32 07:04:19 INFO - warning: no files found matching '*.txt' 07:04:19 INFO - no previously-included directories found matching 'build' 07:04:19 INFO - no previously-included directories found matching 'dist' 07:04:19 INFO - no previously-included directories found matching '.git*' 07:04:19 INFO - Successfully installed jsonschema functools32 07:04:19 INFO - Cleaning up... 07:04:19 INFO - Return code: 0 07:04:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:04:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:19 INFO - 'HOME': '/Users/cltbld', 07:04:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:19 INFO - 'LOGNAME': 'cltbld', 07:04:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:19 INFO - 'MOZ_NO_REMOTE': '1', 07:04:19 INFO - 'NO_EM_RESTART': '1', 07:04:19 INFO - 'PAGER': '/bin/cat', 07:04:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:19 INFO - 'PWD': '/builds/slave/test', 07:04:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:19 INFO - 'SHELL': '/bin/bash', 07:04:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:19 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:19 INFO - 'USER': 'cltbld', 07:04:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:19 INFO - 'XPC_FLAGS': '0x0', 07:04:19 INFO - 'XPC_SERVICE_NAME': '0', 07:04:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:04:19 INFO - Cleaning up... 07:04:19 INFO - Return code: 0 07:04:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:04:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:19 INFO - 'HOME': '/Users/cltbld', 07:04:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:19 INFO - 'LOGNAME': 'cltbld', 07:04:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:19 INFO - 'MOZ_NO_REMOTE': '1', 07:04:19 INFO - 'NO_EM_RESTART': '1', 07:04:19 INFO - 'PAGER': '/bin/cat', 07:04:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:19 INFO - 'PWD': '/builds/slave/test', 07:04:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:19 INFO - 'SHELL': '/bin/bash', 07:04:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:19 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:19 INFO - 'USER': 'cltbld', 07:04:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:19 INFO - 'XPC_FLAGS': '0x0', 07:04:19 INFO - 'XPC_SERVICE_NAME': '0', 07:04:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:19 INFO - Downloading/unpacking blobuploader==1.2.4 07:04:19 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:04:19 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:04:19 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:04:19 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:04:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:04:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:04:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:04:21 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:04:21 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:04:21 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:04:21 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:04:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:04:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:04:21 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:04:21 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:04:21 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:04:21 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:04:22 INFO - Downloading docopt-0.6.1.tar.gz 07:04:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:04:22 INFO - Installing collected packages: blobuploader, requests, docopt 07:04:22 INFO - Running setup.py install for blobuploader 07:04:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:04:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:04:22 INFO - Running setup.py install for requests 07:04:22 INFO - Running setup.py install for docopt 07:04:22 INFO - Successfully installed blobuploader requests docopt 07:04:22 INFO - Cleaning up... 07:04:23 INFO - Return code: 0 07:04:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:23 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:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:23 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:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:23 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:23 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:04:23 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:04:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:23 INFO - 'HOME': '/Users/cltbld', 07:04:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:23 INFO - 'LOGNAME': 'cltbld', 07:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:23 INFO - 'MOZ_NO_REMOTE': '1', 07:04:23 INFO - 'NO_EM_RESTART': '1', 07:04:23 INFO - 'PAGER': '/bin/cat', 07:04:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:23 INFO - 'PWD': '/builds/slave/test', 07:04:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:23 INFO - 'SHELL': '/bin/bash', 07:04:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:23 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:23 INFO - 'USER': 'cltbld', 07:04:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:23 INFO - 'XPC_FLAGS': '0x0', 07:04:23 INFO - 'XPC_SERVICE_NAME': '0', 07:04:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-ATeAln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-WhQ0TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-00iOo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-ZR_HGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-mR5NCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-sZLaOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:04:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:04:23 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-AjmONn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-XJlJGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-U74AL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-uNonF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Hbqmeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-MsdSeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-axY6X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-sF3lMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-yFuoaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-uMvM6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:04:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:04:24 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-vHOdvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:04:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:04:25 INFO - Running setup.py install for manifestparser 07:04:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:04:25 INFO - Running setup.py install for mozcrash 07:04:25 INFO - Running setup.py install for mozdebug 07:04:25 INFO - Running setup.py install for mozdevice 07:04:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:04:25 INFO - Running setup.py install for mozfile 07:04:25 INFO - Running setup.py install for mozhttpd 07:04:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:04:25 INFO - Running setup.py install for mozinfo 07:04:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Running setup.py install for mozInstall 07:04:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Running setup.py install for mozleak 07:04:26 INFO - Running setup.py install for mozlog 07:04:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Running setup.py install for moznetwork 07:04:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Running setup.py install for mozprocess 07:04:26 INFO - Running setup.py install for mozprofile 07:04:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:04:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:04:27 INFO - Running setup.py install for mozrunner 07:04:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:04:27 INFO - Running setup.py install for mozscreenshot 07:04:27 INFO - Running setup.py install for moztest 07:04:27 INFO - Running setup.py install for mozversion 07:04:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:04:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:04:27 INFO - Cleaning up... 07:04:27 INFO - Return code: 0 07:04:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:27 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:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:27 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:04:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:27 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:27 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:04:27 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:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:27 INFO - 'HOME': '/Users/cltbld', 07:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:27 INFO - 'LOGNAME': 'cltbld', 07:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:27 INFO - 'MOZ_NO_REMOTE': '1', 07:04:27 INFO - 'NO_EM_RESTART': '1', 07:04:27 INFO - 'PAGER': '/bin/cat', 07:04:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:27 INFO - 'PWD': '/builds/slave/test', 07:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:27 INFO - 'SHELL': '/bin/bash', 07:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:27 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:27 INFO - 'USER': 'cltbld', 07:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:27 INFO - 'XPC_FLAGS': '0x0', 07:04:27 INFO - 'XPC_SERVICE_NAME': '0', 07:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:04:27 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-axy0dV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-UaXS0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-aZ3mXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-pofSov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-8aQd7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-aFAA7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Pd8aaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Hq0Csm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-bRK_ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-bQJNgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:04:28 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:04:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:04:28 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Wt5FH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:04:29 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-OVVRTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:04:29 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-udvL35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:04:29 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-EcP_8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:29 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-FMXmmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:04:29 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-Oh0j5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:04:29 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:04:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:04:29 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-pR5AsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:04:29 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:04:29 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:04:29 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:04:29 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:04:29 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:04:29 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:04:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:04:29 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:04:29 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:04:29 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:04:29 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:04:31 INFO - Downloading blessings-1.6.tar.gz 07:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:04:31 INFO - Installing collected packages: blessings 07:04:31 INFO - Running setup.py install for blessings 07:04:31 INFO - Successfully installed blessings 07:04:31 INFO - Cleaning up... 07:04:31 INFO - Return code: 0 07:04:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:04:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:04:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:04:31 INFO - Reading from file tmpfile_stdout 07:04:31 INFO - Current package versions: 07:04:31 INFO - blessings == 1.6 07:04:31 INFO - blobuploader == 1.2.4 07:04:31 INFO - docopt == 0.6.1 07:04:31 INFO - functools32 == 3.2.3-2 07:04:31 INFO - jsonschema == 2.5.1 07:04:31 INFO - manifestparser == 1.1 07:04:31 INFO - mozInstall == 1.12 07:04:31 INFO - mozcrash == 1.0 07:04:31 INFO - mozdebug == 0.1 07:04:31 INFO - mozdevice == 0.50 07:04:31 INFO - mozfile == 1.2 07:04:31 INFO - mozhttpd == 0.7 07:04:31 INFO - mozinfo == 0.9 07:04:31 INFO - mozleak == 0.1 07:04:31 INFO - mozlog == 3.4 07:04:31 INFO - moznetwork == 0.27 07:04:31 INFO - mozprocess == 0.25 07:04:31 INFO - mozprofile == 0.28 07:04:31 INFO - mozrunner == 6.13 07:04:31 INFO - mozscreenshot == 0.1 07:04:31 INFO - mozsystemmonitor == 0.3 07:04:31 INFO - moztest == 0.8 07:04:31 INFO - mozversion == 1.4 07:04:31 INFO - psutil == 3.1.1 07:04:31 INFO - requests == 1.2.3 07:04:31 INFO - wsgiref == 0.1.2 07:04:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:31 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:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:31 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:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:31 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 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:31 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:04:31 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:04:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:31 INFO - 'HOME': '/Users/cltbld', 07:04:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:31 INFO - 'LOGNAME': 'cltbld', 07:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:31 INFO - 'MOZ_NO_REMOTE': '1', 07:04:31 INFO - 'NO_EM_RESTART': '1', 07:04:31 INFO - 'PAGER': '/bin/cat', 07:04:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:31 INFO - 'PWD': '/builds/slave/test', 07:04:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:31 INFO - 'SHELL': '/bin/bash', 07:04:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:31 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:31 INFO - 'USER': 'cltbld', 07:04:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:31 INFO - 'XPC_FLAGS': '0x0', 07:04:31 INFO - 'XPC_SERVICE_NAME': '0', 07:04:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 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:04:32 INFO - Cleaning up... 07:04:32 INFO - Return code: 0 07:04:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103ffcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103fff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4ea46ad90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, '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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:04:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:32 INFO - 'HOME': '/Users/cltbld', 07:04:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:32 INFO - 'LOGNAME': 'cltbld', 07:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:32 INFO - 'MOZ_NO_REMOTE': '1', 07:04:32 INFO - 'NO_EM_RESTART': '1', 07:04:32 INFO - 'PAGER': '/bin/cat', 07:04:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:32 INFO - 'PWD': '/builds/slave/test', 07:04:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:32 INFO - 'SHELL': '/bin/bash', 07:04:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:32 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:32 INFO - 'USER': 'cltbld', 07:04:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:32 INFO - 'XPC_FLAGS': '0x0', 07:04:32 INFO - 'XPC_SERVICE_NAME': '0', 07:04:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:04:32 INFO - Cleaning up... 07:04:32 INFO - Return code: 0 07:04:32 INFO - Running post-action listener: _resource_record_post_action 07:04:32 INFO - Running post-action listener: _start_resource_monitoring 07:04:32 INFO - Starting resource monitoring. 07:04:32 INFO - [mozharness: 2017-05-12 14:04:32.382637Z] Finished create-virtualenv step (success) 07:04:32 INFO - [mozharness: 2017-05-12 14:04:32.383575Z] Running install step. 07:04:32 INFO - Running pre-action listener: _resource_record_pre_action 07:04:32 INFO - Running main action method: install 07:04:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:04:32 INFO - Reading from file tmpfile_stdout 07:04:32 INFO - Detecting whether we're running mozinstall >=1.0... 07:04:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:04:32 INFO - Reading from file tmpfile_stdout 07:04:32 INFO - Output received: 07:04:32 INFO - Usage: mozinstall [options] installer 07:04:32 INFO - Options: 07:04:32 INFO - -h, --help show this help message and exit 07:04:32 INFO - -d DEST, --destination=DEST 07:04:32 INFO - Directory to install application into. [default: 07:04:32 INFO - "/builds/slave/test"] 07:04:32 INFO - --app=APP Application being installed. [default: firefox] 07:04:32 INFO - mkdir: /builds/slave/test/build/application 07:04:32 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:04:32 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:04:53 INFO - Reading from file tmpfile_stdout 07:04:53 INFO - Output received: 07:04:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:04:53 INFO - Running post-action listener: _resource_record_post_action 07:04:53 INFO - [mozharness: 2017-05-12 14:04:53.220510Z] Finished install step (success) 07:04:53 INFO - [mozharness: 2017-05-12 14:04:53.220648Z] Running run-tests step. 07:04:53 INFO - Running pre-action listener: _resource_record_pre_action 07:04:53 INFO - Running main action method: run_tests 07:04:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:04:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:04:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:04:53 INFO - 2017-05-12 07:04:53.235 screenresolution[1625:7376] starting screenresolution argv=screenresolution get 07:04:53 INFO - 2017-05-12 07:04:53.238 screenresolution[1625:7376] Display 0: 1600x1200x32@60 07:04:53 INFO - 2017-05-12 07:04:53.243 screenresolution[1626:7379] starting screenresolution argv=screenresolution list 07:04:53 INFO - Available Modes on Display 0 07:04:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:04:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:04:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:04:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:04:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:04:53 INFO - Intel Iris: 07:04:53 INFO - Chipset Model: Intel Iris 07:04:53 INFO - Type: GPU 07:04:53 INFO - Bus: Built-In 07:04:53 INFO - VRAM (Dynamic, Max): 1536 MB 07:04:53 INFO - Vendor: Intel (0x8086) 07:04:53 INFO - Device ID: 0x0a2e 07:04:53 INFO - Revision ID: 0x0009 07:04:53 INFO - Displays: 07:04:53 INFO - Display: 07:04:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:04:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:04:53 INFO - Display Serial Number: 1600x1200 60 07:04:53 INFO - Main Display: Yes 07:04:53 INFO - Mirror: Off 07:04:53 INFO - Online: Yes 07:04:53 INFO - Rotation: Supported 07:04:53 INFO - Return code: 0 07:04:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:04:53 INFO - Python 2.7.3 07:04:53 INFO - Return code: 0 07:04:53 INFO - grabbing minidump binary from tooltool 07:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:53 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 0x103d0dd30>, '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 0x103cc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103d01be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:04:53 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:04:53 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:04:53 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:04:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:04:53 INFO - Return code: 0 07:04:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:04:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:04:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:04:53 INFO - ENV: RUST_BACKTRACE is now 1 07:04:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:04:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:04:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-0234', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --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-0234 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtTQl4Q3e1/Render', 07:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:04:53 INFO - 'HOME': '/Users/cltbld', 07:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:04:53 INFO - 'LOGNAME': 'cltbld', 07:04:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:04:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:53 INFO - 'MOZ_NO_REMOTE': '1', 07:04:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:04:53 INFO - 'NO_EM_RESTART': '1', 07:04:53 INFO - 'PAGER': '/bin/cat', 07:04:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:53 INFO - 'PWD': '/builds/slave/test', 07:04:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:04:53 INFO - 'RUST_BACKTRACE': '1', 07:04:53 INFO - 'SHELL': '/bin/bash', 07:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gvimQhlFWk/Listeners', 07:04:53 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 07:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:04:53 INFO - 'USER': 'cltbld', 07:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:53 INFO - 'XPC_FLAGS': '0x0', 07:04:53 INFO - 'XPC_SERVICE_NAME': '0', 07:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:04:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--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-0234', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:04:53 INFO - mozversion application_buildid: 20170512054043 07:04:53 INFO - mozversion application_changeset: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 07:04:53 INFO - mozversion application_display_name: Nightly 07:04:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:04:53 INFO - mozversion application_name: Firefox 07:04:53 INFO - mozversion application_remotingname: firefox 07:04:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:04:53 INFO - mozversion application_vendor: Mozilla 07:04:53 INFO - mozversion application_version: 55.0a1 07:04:53 INFO - mozversion platform_buildid: 20170512054043 07:04:53 INFO - mozversion platform_changeset: 030c0a7c878192d8dc1b04c182575c2f7858cdd0 07:04:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:04:53 INFO - mozversion platform_version: 55.0a1 07:04:53 INFO - using testdate: 1494597893 07:04:53 INFO - actual date: 1494597893 07:04:53 INFO - starting webserver on 'localhost:49221' 07:04:53 INFO - SUITE-START | Running 2 tests 07:04:53 INFO - TEST-START | dromaeo_css 07:04:53 INFO - operating with platform_type : mac_ 07:04:53 INFO - Initialising browser for dromaeo_css test... 07:04:53 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpypn3n9/profile http://localhost:49221/getInfo.html) 07:04:56 INFO - PID 1636 | __metrics Screen width/height:1600/1200 07:04:56 INFO - PID 1636 | colorDepth:24 07:04:56 INFO - PID 1636 | Browser inner width/height: 1024/691 07:04:56 INFO - PID 1636 | __metrics 07:04:56 INFO - PID 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:04:56 INFO - PID 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:04:57 INFO - TEST-INFO | 1636: exit 0 07:04:57 INFO - Browser initialized. 07:04:57 INFO - Running cycle 1/1 for dromaeo_css test... 07:04:57 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpypn3n9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:05:09 INFO - PID 1638 | RSS: Main: 235618304 07:05:09 INFO - PID 1638 | 07:08:13 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 07:08:15 INFO - PID 1638 | RSS: Main: 385024000 07:08:15 INFO - PID 1638 | 07:11:18 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 07:11:18 INFO - PID 1638 | RSS: Main: 370438144 07:11:18 INFO - PID 1638 | 07:14:20 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 07:14:21 INFO - PID 1638 | RSS: Main: 347979776 07:14:21 INFO - PID 1638 | 07:17:23 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 07:17:24 INFO - PID 1638 | RSS: Main: 334766080 07:17:24 INFO - PID 1638 | 07:20:28 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 07:20:28 INFO - PID 1638 | RSS: Main: 331935744 07:20:28 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 07:23:30 INFO - PID 1638 | RSS: Main: 381128704 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | __start_tp_report 07:23:30 INFO - PID 1638 | _x_x_mozilla_page_load 07:23:30 INFO - PID 1638 | _x_x_mozilla_page_load_details 07:23:30 INFO - PID 1638 | |i|pagename|runs| 07:23:30 INFO - PID 1638 | |0;dojo.html;3452.7410444216675;3456.7925924444553;3457.809820459875;3462.7922324660512;3479.165000399905;5885.352611212763;6015.157877897087;6055.909161362578;6059.091136329553;6097;5448.291722076125;5527.281453411061;5956.3150237722675;6004.069369247763;6014.518838492921;5185.533302002819;5200.479952004807;5208.869778255555;5213.608979326546;5221.973890130544;5303.071962406582;5307.575393968493;5313.840584782462;5318.228856815759;5323.121684921984;5306.204069389584;5307.442718514559;5308.23030660554;5311.309529761145;5320.600954928396;5302.257683924254;5315.867103322428;5361.973190134025;5393.352797664305;5393.7572809223675;5202.843914682566;5327.200919862013;5330.573554115662;5332.3601167859715;5362.597805164589;6182.103594978703;6187.87624247517;6189.597676151036;6192.597481163755;6195.504359651262;4925.926111108336;6190.257169139687;6281.057841323793;6351.142595749561;6379.87240255197;6363.745450182034;6412.487001039905;6440.677966101676;6453.806385808433;6487.286398496161;6186.381361863778;6199.380061993765;6199.50403967686;6208.720607572715;6220.720067596879;6411.519136064814;6502.544821862424;6623.437007854386;6637.966810165918;6648.734050638017;6259.405356491126;6260.624362538262;6264.686765661699;6271.4669253114;6293.748250069946;6278.843028924313;6279.4034566717;6401.551891367652;6475.158229430238;6483;6281.466075383643;6313.747450101944;6317.747290108436;6353.205849268841;6362.554621176473;6274.1843560337775;6355.777547785805;6356.713947872357;6376.936230637727;6389.9680501597195;5037.924431133536;5107.821226257063;5140.56305214061;5167.147420675622;5175;4554.567316104964;4600.7929643166135;4662.976685116552;4667.789949452283;4688.789004494806;6191.318954914956;6199.132121502903;6201.875962480725;6211.9068213976825;6214.658193799294;6497.122888410098;6498.54510184292;6502.512311576556;6514.478841692653;6521.347865213441;6001.879962400728;6041.124037014608;6062.393760623991;6111.92601248044;6187.102870083812;5681.289838770153;5714.228579141763;5717.885642287214;5720.227769251181;5730.34101078373;6586.901196482152;6616.999999999904;6617.239017512951;6618.768343107968;6675.23234828001;6436.195475565554;6438.323975982455;6472.190976127984;6484.708188131451;6492.220933488012;6389.616623002635;6642.83392915181;6669.066330713801;6671.666416679243;6673.366030227121;6343.4608058313715;6343.9682801585695;6360.809175724736;6372.681365931778;6390.616563006234;6430.2926678066315;6477.449416799436;6477.902831457626;6485.448736857231;6501.414872661483;6147.600405973598;6261.843453913689;6309.968450157719;6318.431341179132;6318.747250109944;6227.4395304421005;6249.437550620466;6299.685015749286;6307.90538141919;6318.6840657965995;6260.373962603703;6305.684715764101;6307.463865571478;6324.936750632435;6326.114343991753;6244.562880598314;6297.496200303873;6303.842403939938;6308.400701933309;6331.461825744863;6104.969475152596;6173.290071641819;6251.624902505864;6276.560640755103;6292.559520833498 07:23:30 INFO - PID 1638 | |1;ext.html;760.3157158557301;782.4601025292548;791.1692722641226;1120.9719757006078;1130.2088538023386;25555.872220638917;35130.4191311391;35285;35357.46963795545;35409.82295088521;37541.62458375416;37964.35623765844;37965.29156187971;38023.479060837606;38134.809325953334;5801.6519008859395;7881.251281128312;8053.275205231528;8055.959720201391;8065.879011814827;5777.248957635523;7815.413843961725;7903.3282171015535;7907.090684571262;7910.406719496061;7943.682252709885;8001.279884810366;8006;8013.799655008643;8018.558979256139;5751.597388182829;7944.642491087886;7980.880286795703;7990.720324788663;7996.600169991535;5761.53907687384;7728.922710772933;7812.179721129256;7822.295993360625;7825.804354891116;34296.314073718386;34592.48111278333;34649.82675086609;34656.82671586651;34666.65333346685;3491.6159222485508;3526.576810782697;3527.276908233806;3527.7883327000204;3530.4174811156126;28004.299892502648;28065.438691226267;28118.718812811814;28138.296542586395;28167.154985350473;3022.07826612883;3063.89276375328;3080.92167741291;3115.43922094023;3116.5792617996735;297476;297595;297829;298696;298788;45903;46192.076158476644;46210;46259.76870115628;46312.84217894579;47881;47927.5207247923;47949.28076078861;48053.798655033905;48207;13177.670558236121;13251.939844812392;13261;13270.402831872396;13275.070745047755;8373.8325233495;8413.116622754646;10987.285826421257;10990.450477476094;11003.889961100447;9617.807643847083;9777.022297770167;10684.611000570032;11028.503717332578;11231.943840280746;4482.596566309048;5025.221090730943;5133.512316329943;5148.819791307286;5165.199394093921;848.5587494502824;957.5403806173076;977.6675930183629;993.5975929748463;1020.6937918624383;22883.31350059501;23129.53740925172;23148.65277020846;23182.65226021611;23186.956586953627;916.8716379706847;1067.3169171730099;1078.455380033094;1120.2998126171144;1134.8014097532898;11777.411129443493;11870.406479675981;11886.762264754656;11913.523459061715;11946.402679865972;303335;303552;304270;305244;305371;48327.27509087366;48467.27299090516;48574.27138592924;48637.54087377384;48663;1485.2054151029147;1489.7616381378928;1529.6634740357104;1530.211940850463;1549.6590750034975;21588.244411445525;22747.090116395822;22755.65866512037;22758.317250482512;22777.202797901995;34017.31965360729;34139.31721365608;34140;34229.52327627568;34235.4864677025;259438;260342.69828650737;260542;260606;261261;25214.24357269285;26717;30807.075787726404;30809.691903080682;30833.845830770704;33181.50227746634;38666.226675466896;38815.80592097021;40233.597664022986;40433.59566404298;25335.746642534075;25590.057183099783;26460.338491537867;26716.194799545767;26784;25595;25599.333743134302;26378.472430551665;26989.460210796067;27008;32057.35885282328;32867.01398958035;33225.66774332226;33643.83178084094;33799.83100084484;23112.768872311062;25329.113481028075;28418.573721393783;29007;29482.705172947997;19072.33246836354;21253.89373053125;22266.109355625595;22624.321270361914;22784.772152278267 07:23:30 INFO - PID 1638 | |2;jquery.html;376.8280647187247;401.5201833808599;420.9747415155091;428.79846472158073;452.7374123008655;20470.385888423352;20839.791602083973;21203.57592848142;22149.778502214973;22479.55040899185;21864.672029919562;22447.102115915386;23022.9639666215;23331.76668233317;23498;5715.771109211519;5960.344362120163;6175;6187.721552530124;6191.628502289855;5573.637713548626;5740.7129643517865;5859.150423188635;6111.083337499366;6183.7217325220245;4802.59177969873;6047.304559975593;6145.078238264251;6157.538184636171;6169.118418882239;5881.794137205198;6025.517958563326;6034.064719968434;6034.396560343975;6044.909326360108;5093.261477085839;5315.176147697113;5327.920081198785;5361.651492652965;5375.569954403638;22419;26388.60417093745;26726.5991010135;26879.596806047924;27741.612919354153;2135.4661334666334;2218.5673793610254;2221.4335344487054;2221.733391992971;2223.1663126327626;17458.825411745845;17742.93542387461;17746.822531774647;17852.642947141114;17904.373346932796;2077.1483691686335;2111.440468275908;2133.7012818205462;2133.775953524873;2133.9573208535744;142313;142628;142713;143945.280273599;144322;10708.94645526783;11160.883911608937;11539.711507212387;12054.51781928713;12139.757204855854;13869.09850859691;16211.51365459038;16217.59456013609;16241.431549895697;16270.105144216945;7184.1019872515935;8340.499570025819;8685.131486851264;8793.868091978626;8811.251043661232;8106.756797296091;8111.990285750898;8132.308753755997;8320.583970801557;8353.081161072278;6221.573586301346;6396.42432181106;6429.45349645276;7604.657790399446;7627.161012288644;2099.086897199723;2108.5240331767823;2113.3342996955907;2125.861818981761;2281.6577513373127;949.349695458613;952.2810278239884;959.7858708733457;982.1798798003747;993.9055789699967;20484.487887802625;21079.26222582202;21178.046987885584;21207.78792212089;21408.929553522263;910.797746973988;916.4272329793879;983.0464449483989;1009.3439264478149;1015.081351377005;10808.081313088476;10859.34843909368;11000.889991099986;11015.228933974706;11050.723731906766;512792;520474;523765;532948;537474;81917;103752.48123759333;116270.4186479079;129405;134237;1153.5618410302188;1153.6193056291404;1197.2218058262026;1203.9518419263306;1205.7407657353535;22284.554308914056;22398.664020039385;22480;22686.546269074195;22810.885945570168;56756.71621641865;58091.70954145202;58476.707616461645;58514;58996.82006359935;78222;78832;78910.6054469724;79648;80836.91630836869;8726.30189584819;8745.781355466164;8878.422902511316;8904.73285801427;9017.684381046633;13796.310184490534;13917.930410347883;13947.651308716957;14335.283235838375;14343.282835857957;9649.421034737938;11236.213465057368;12021.75956480883;12034.338111403957;12058.638240852437;9395.342326037111;10340.81080675732;10492.317999330018;10513.421761802869;10858.565657373616;11745.942865142179;11910.52357905674;12032.699182520157;12126.514939402678;12156;8647.654093836176;8794.560271986504;8801.559922004002;8804.559772011246;8811.251043661361;11574.421278936188;11767.470463829319;11816.46825892854;12045.51817927273;12093.274403535816 07:23:30 INFO - PID 1638 | |3;mootools.html;1355.8440779310379;1400.285854214351;1419.0846903747076;1424.1526291856342;1427.0581416265263;11424.314541127527;11944.402779861015;11949.34278614678;11957.342346170957;11962.10284228682;11210.775784484305;11606.941965290162;11947.283163010225;11957.162998590102;11958.581449649264;2524.154408273226;2559.2834006478215;2571.3700143464876;2572.5498037843395;2573.935651608706;2487.4900645367657;2528.696556413233;2531.8860651270643;2532.050481069599;2537.619357096432;2472.2570587735604;2578.8323758955607;2578.922632321024;2579.57437022891;2582.4705935283223;2545.707243666965;2548.7833534149527;2572.5755250383663;2572.6269690894715;2576.6601367289;2519.987400063006;2545.5036267927762;2552.8468291902545;2555.0546297869723;2555.4122551813;11377.431128443544;11646.708832279177;11655.30068195911;11667.183297169202;11668.35824029678;6465.644389558573;6682.198136223636;6708.228553716336;6714.83212919676;6718.361755633207;3845.3462911305146;4124.072083781155;4200.075983283673;4215.3044747616605;4217.924429270547;1077.348204336373;1179.799434096197;1192.439553409896;1238.7708273969345;1245.5951815659855;14914.701705965928;15247.542573722805;15282.235888205545;15911.60220994473;16031.91984040084;5267.578593712494;5421.566274698014;5568.6658800472105;5568.860778480489;5577.191307260425;11599.304041757521;11605.419729013516;11610;11619.76760464786;11623.825642615368;6452.580582262138;6464.676766161673;6466.321036291216;6468.2561505427475;6468.482521398277;6002.519798416116;6098.603590766586;6190.2571691397325;6202.658853763086;6296.622202667854;4175.561566035583;4313.676474264443;4323.3947247385395;4370.431843860278;4375.671824613103;1268.6828292926768;1270.2315099364937;1273.089740835307;1288.1112032697408;1290.8967282617368;722.692855536395;734.5482528245197;737.0455260437745;741.6514238307986;760.6577040331873;4816;5595.7481913313995;5596.944030559724;5602.803901863415;5683.204351390726;557.3395526301346;578.8784355285353;580.7067430947429;582.2023827356549;596.5525855608294;3279.918002049968;3676.8713095041544;3886.2810380079777;3900.083480382108;3904.648581627667;13707;14597.78103328451;14598.34307456167;15118.924405378122;15123.697526049418;14340.426382944564;14847;14943.626409339853;15036.849631503765;15101;8217.589120544068;8351.95824020876;8562.614682339185;8618.827623447496;8639.092895246034;5053.772580233825;6068.817935461943;6301.369863013662;6372.745090196526;6459.6770161490795;12958;13183.53857614979;13245.867541324464;13645.658858528615;13666.248356340497;13436.328183590585;13618.93190534041;13736;13791.655208619864;14112.576622701336;9190.85114360705;9241.722748317561;9434.150926416656;9435.339526233372;9437.528123593656;10309.07218350352;10516.842247366449;10635.042846143884;10642.73393165177;10655.774585922718;8707.651693932172;8762.868556971524;8785.868211976698;8841.248493877834;8906.59920303575;8344.874826877724;8587.828243434971;8623.930256738213;8631.784205394664;8670.176333248332;7889;8015.198480151938;8018.4787988780545;8043.3565314773505;8047.919520804951;5224.555912747459;5283.286756287852;5367.35591728995;5375.865603359947;5502.880104515266;5354.598405119709;5368.731563421891;5410.78356865721;5446.727663616724;5482.122860342485 07:23:30 INFO - PID 1638 | |4;prototype.html;380.10674913952215;393.89364871484696;405.42024904386733;405.663299461447;406.40258819535273;5526.861828454285;5874.9118763218585;5930.970345148274;5953.970230148843;5978.312494063184;4532.52408497108;4607.470140933794;5780.248567686205;5791.333996590393;5905.228431470563;4286.57134286572;4290.854572713643;4304.3643415502065;4314.71954322968;4324.783760811971;4300.225959327336;4313.805878735465;4322.762248076355;4326.961529232977;4344.826206951718;4102.917941641165;4258.893527661818;4267.46371306331;4272;4280;3145.3394787094508;4088.998195442106;4256.553061928515;4261.74429534229;4274.807633656493;3657.250263695936;3694.649008344203;3694.9261014779822;3698.112453011258;3714.2014466889477;4204.285271503852;5585.2459917910965;5702.800401985952;5728.25532680753;5823.272090988627;4179.373094035888;4195.895102622429;4992.176290911995;5008.24876268559;5023.899522009539;5319.8562309103345;5934.673592952386;6174.783882564134;6190.747345181399;6221.657808820513;3646.5441819772527;3927.6465118139504;3929.8231579578987;4118.114605359832;4140.523839758436;6319;6494.993276042221;6507.967460162668;6545.410913017851;6610.8016759497295;4054.2499637566557;4184.916301673949;4198.412222288882;4214.840918747308;4215.662746980234;3874.2638898609175;4215.494140703136;4237.533871274158;4261.27558315081;4275.615194632436;4567.977160114245;4696.694714843524;4742.644301677388;4883.658143929962;5021.744563859035;5258.526732594085;5671.262735844314;5864.9472684560915;6195.845103872439;6276.748930042839;1293.5666551705285;1376.5526203983745;1433.7849322601694;1445.9060161089496;1472.168224952898;918.9770255743526;963.7253382785873;965.6571916969515;969.2924165359327;978.5841017567506;5543.113101903676;5611.354694210137;5906.793262235801;5919.585629006015;5949.940500595025;6332.873342533124;6428.3893030163;6450.451711604473;6452.903206451907;6534.607923524603;5461.644993075437;5808.3901190375245;5900.970495147582;5952.196453478811;5988.071848863433;3305.1905880002287;3364.9327013459592;3410.7612467127065;3410.9488357674654;3442.277121804399;5408.621396502207;5561.138023606348;5726.742296596663;6674.532782705164;6687.933120668828;5548.69482178484;6583.9012414813815;6587.934120658732;6612.471002319803;6657.434118100015;6078.45293923549;6369.235691717024;6510.707018184193;6586.473082153416;6604.075429439787;5947.494462970696;6397.840053998687;6398.968005159944;6412.422881940648;6412.871742565216;6058.485028772604;6229.536059026034;6300.842478938063;6306.306306306395;6330.746770129143;6305.495560355069;6420.390062944013;6455.483561315177;6461.418472337512;6655.633940133339;3542.8228588570983;3668.743187976816;4049.676025917978;4113.814878330422;4183.640316897082;5673.659580425187;6181.907271390843;6442.130312407952;6450.097221319628;6464.967675161594;5296.655717378358;5561;6198.81403557894;6279.645133075097;6281.4660753834605;5318.61715953847;5922.2597175353085;6270.6864656766065;6279.497640188865;6284.685765711788;5590.357108932525;5850.882982340244;6128.63228206309;6193.256809182927;6222.875542489215;5353.625246232727;5944.138099975566;6267.9373206267355;6319.494440444845;6326.398992095744;6021.789237376671;6117.3576774439825;6155.091921504348;6193.690315484298;6264.154339164154 07:23:30 INFO - PID 1638 | |5;yui.html;817.8691409374501;834.2033358142972;836.3267569606464;841.1084250694264;855.9272461840743;16422.178891055446;16860.83139168611;16863.662726745457;16876.156192190403;16885.24016419261;15217.858660600445;16684.83315166851;17798.466046018606;17959.19183636736;18061.36785212518;1006.6929586476122;1029.9382037077762;1030.1398332392423;1031.9638812641558;1033.9896601034006;1049.2655141401049;1051.8579991701138;1052.1319911073394;1053.9934362683618;1057.3550134417999;1044.6448207609412;1056.5773690523774;1060.787842431513;1062.4315990944847;1079.6221322537128;1052.4106500359824;1056.804491169136;1059.475559598;1061.6496556136456;1081.1512962324578;791.76247125862;792.3898598079446;795.586295126531;797.5653268968426;798.9880151797728;16798.65610751137;16921.57696057596;17004.914975425167;17073;17283.395081172224;16937.407190748265;17078.914605426893;17235.22441490136;17416.912915435467;17461;14091.929540352232;14268.35913869908;14288.714225715532;14458.855411445857;14464.927675361661;3574.803385813805;3594.5327107476123;3595.1191957970204;3599.0822340303057;3603.1712706077487;118715.4064229682;118722.40638796837;119238;123559;125864;12981.286029268293;13009.869901301056;13139.802902956464;13168.341582920815;13208.669783255495;25572.872135639576;25579.87210063938;25946.74053259443;25976.090836820622;26006.34984125412;7245.420366370678;7308.59802710856;7323.157836848724;7343.449241306924;7378.446616503784;3873.6320049595242;4002.45966794479;4014.016565941363;4021.4571032910767;4021.4772079630047;4327.826886924488;4388.978055109704;4405.493368262691;4429.335599660077;4466.932996005063;862.8015556422058;886.7517095213351;887.1660638999319;887.60057973912;888.8666699995052;643.5045015338256;668.3350066683583;670;680.5270337115658;685.372883811317;10990.505427255792;11638.476268567934;11756;11868.287902725864;12114.273143611412;618.5051958433326;644.3813938618978;650.3788881618043;658.06554692337;670.7484693240035;5028.03561939099;5267.446918073624;5402.740217245153;5660.462256085665;5729.541636669056;105221.47389263005;106013;107415;108192.37711434177;109008.36487452695;95738;96421;96628.0337196633;97197.5420368695;98236.50881745688;84838.18228179746;85115.5744221275;86084;86097;86736.56631716927;10183.949080254552;10411.630688897025;10938.265433641589;11202.94398528002;11286.887131128584;34316.97049088532;37668.43497347597;42056.78971605122;42567.722968311;43608.34587481127;55687.44312556823;56509;56565.71717141553;57304.42695573158;57852.710736446046;14159.858401416266;14297.571072867831;14341;14622.853771462575;14635.634109147357;15095;15452.613684657525;15591.454299099478;15591.532254032398;15623;13750.587482375544;13773.38019789132;13984.580462586138;14106.929465352607;14152.292385380895;13031.283279419724;13418;13754.518591849237;13850.09974351664;13886.02797804144;11740.882591173979;12015.399230038638;12020.218685785396;12119.212251203644;12177.878221217674;10393.06462418386;10401.895981040092;10618.575256989634;11038.503267352828;11103.72240693989;13482.123661961941;13787.576347372631;13918.51285205013;13938.79091813644;14002.649933751738 07:23:30 INFO - PID 1638 | __end_tp_report 07:23:30 INFO - PID 1638 | __start_cc_report 07:23:30 INFO - PID 1638 | _x_x_mozilla_cycle_collect,1622 07:23:30 INFO - PID 1638 | __end_cc_report 07:23:30 INFO - PID 1638 | __startTimestamp1494599010977__endTimestamp 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | ------- Summary: start ------- 07:23:30 INFO - PID 1638 | Number of tests: 6 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:5960.19 Median:6249.44 stddev:678.53 (10.9%) stddev-sans-first:651.47 07:23:30 INFO - PID 1638 | Values: 3452.7 3456.8 3457.8 3462.8 3479.2 5885.4 6015.2 6055.9 6059.1 6097.0 5448.3 5527.3 5956.3 6004.1 6014.5 5185.5 5200.5 5208.9 5213.6 5222.0 5303.1 5307.6 5313.8 5318.2 5323.1 5306.2 5307.4 5308.2 5311.3 5320.6 5302.3 5315.9 5362.0 5393.4 5393.8 5202.8 5327.2 5330.6 5332.4 5362.6 6182.1 6187.9 6189.6 6192.6 6195.5 4925.9 6190.3 6281.1 6351.1 6379.9 6363.7 6412.5 6440.7 6453.8 6487.3 6186.4 6199.4 6199.5 6208.7 6220.7 6411.5 6502.5 6623.4 6638.0 6648.7 6259.4 6260.6 6264.7 6271.5 6293.7 6278.8 6279.4 6401.6 6475.2 6483.0 6281.5 6313.7 6317.7 6353.2 6362.6 6274.2 6355.8 6356.7 6376.9 6390.0 5037.9 5107.8 5140.6 5167.1 5175.0 4554.6 4600.8 4663.0 4667.8 4688.8 6191.3 6199.1 6201.9 6211.9 6214.7 6497.1 6498.5 6502.5 6514.5 6521.3 6001.9 6041.1 6062.4 6111.9 6187.1 5681.3 5714.2 5717.9 5720.2 5730.3 6586.9 6617.0 6617.2 6618.8 6675.2 6436.2 6438.3 6472.2 6484.7 6492.2 6389.6 6642.8 6669.1 6671.7 6673.4 6343.5 6344.0 6360.8 6372.7 6390.6 6430.3 6477.4 6477.9 6485.4 6501.4 6147.6 6261.8 6310.0 6318.4 6318.7 6227.4 6249.4 6299.7 6307.9 6318.7 6260.4 6305.7 6307.5 6324.9 6326.1 6244.6 6297.5 6303.8 6308.4 6331.5 6105.0 6173.3 6251.6 6276.6 6292.6 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:42230.12 Median:33024.26 stddev:75768.69 (229.4%) stddev-sans-first:75917.26 07:23:30 INFO - PID 1638 | Values: 760.3 782.5 791.2 1121.0 1130.2 25555.9 35130.4 35285.0 35357.5 35409.8 37541.6 37964.4 37965.3 38023.5 38134.8 5801.7 7881.3 8053.3 8056.0 8065.9 5777.2 7815.4 7903.3 7907.1 7910.4 7943.7 8001.3 8006.0 8013.8 8018.6 5751.6 7944.6 7980.9 7990.7 7996.6 5761.5 7728.9 7812.2 7822.3 7825.8 34296.3 34592.5 34649.8 34656.8 34666.7 3491.6 3526.6 3527.3 3527.8 3530.4 28004.3 28065.4 28118.7 28138.3 28167.2 3022.1 3063.9 3080.9 3115.4 3116.6 297476.0 297595.0 297829.0 298696.0 298788.0 45903.0 46192.1 46210.0 46259.8 46312.8 47881.0 47927.5 47949.3 48053.8 48207.0 13177.7 13251.9 13261.0 13270.4 13275.1 8373.8 8413.1 10987.3 10990.5 11003.9 9617.8 9777.0 10684.6 11028.5 11231.9 4482.6 5025.2 5133.5 5148.8 5165.2 848.6 957.5 977.7 993.6 1020.7 22883.3 23129.5 23148.7 23182.7 23187.0 916.9 1067.3 1078.5 1120.3 1134.8 11777.4 11870.4 11886.8 11913.5 11946.4 303335.0 303552.0 304270.0 305244.0 305371.0 48327.3 48467.3 48574.3 48637.5 48663.0 1485.2 1489.8 1529.7 1530.2 1549.7 21588.2 22747.1 22755.7 22758.3 22777.2 34017.3 34139.3 34140.0 34229.5 34235.5 259438.0 260342.7 260542.0 260606.0 261261.0 25214.2 26717.0 30807.1 30809.7 30833.8 33181.5 38666.2 38815.8 40233.6 40433.6 25335.7 25590.1 26460.3 26716.2 26784.0 25595.0 25599.3 26378.5 26989.5 27008.0 32057.4 32867.0 33225.7 33643.8 33799.8 23112.8 25329.1 28418.6 29007.0 29482.7 19072.3 21253.9 22266.1 22624.3 22784.8 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:34136.44 Median:12546.27 stddev:88795.90 (707.7%) stddev-sans-first:89008.82 07:23:30 INFO - PID 1638 | Values: 376.8 401.5 421.0 428.8 452.7 20470.4 20839.8 21203.6 22149.8 22479.6 21864.7 22447.1 23023.0 23331.8 23498.0 5715.8 5960.3 6175.0 6187.7 6191.6 5573.6 5740.7 5859.2 6111.1 6183.7 4802.6 6047.3 6145.1 6157.5 6169.1 5881.8 6025.5 6034.1 6034.4 6044.9 5093.3 5315.2 5327.9 5361.7 5375.6 22419.0 26388.6 26726.6 26879.6 27741.6 2135.5 2218.6 2221.4 2221.7 2223.2 17458.8 17742.9 17746.8 17852.6 17904.4 2077.1 2111.4 2133.7 2133.8 2134.0 142313.0 142628.0 142713.0 143945.3 144322.0 10708.9 11160.9 11539.7 12054.5 12139.8 13869.1 16211.5 16217.6 16241.4 16270.1 7184.1 8340.5 8685.1 8793.9 8811.3 8106.8 8112.0 8132.3 8320.6 8353.1 6221.6 6396.4 6429.5 7604.7 7627.2 2099.1 2108.5 2113.3 2125.9 2281.7 949.3 952.3 959.8 982.2 993.9 20484.5 21079.3 21178.0 21207.8 21408.9 910.8 916.4 983.0 1009.3 1015.1 10808.1 10859.3 11000.9 11015.2 11050.7 512792.0 520474.0 523765.0 532948.0 537474.0 81917.0 103752.5 116270.4 129405.0 134237.0 1153.6 1153.6 1197.2 1204.0 1205.7 22284.6 22398.7 22480.0 22686.5 22810.9 56756.7 58091.7 58476.7 58514.0 58996.8 78222.0 78832.0 78910.6 79648.0 80836.9 8726.3 8745.8 8878.4 8904.7 9017.7 13796.3 13917.9 13947.7 14335.3 14343.3 9649.4 11236.2 12021.8 12034.3 12058.6 9395.3 10340.8 10492.3 10513.4 10858.6 11745.9 11910.5 12032.7 12126.5 12156.0 8647.7 8794.6 8801.6 8804.6 8811.3 11574.4 11767.5 11816.5 12045.5 12093.3 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6936.87 Median:3478.39 stddev:4451.55 (128.0%) stddev-sans-first:4444.28 07:23:30 INFO - PID 1638 | Values: 1355.8 1400.3 1419.1 1424.2 1427.1 11424.3 11944.4 11949.3 11957.3 11962.1 11210.8 11606.9 11947.3 11957.2 11958.6 2524.2 2559.3 2571.4 2572.5 2573.9 2487.5 2528.7 2531.9 2532.1 2537.6 2472.3 2578.8 2578.9 2579.6 2582.5 2545.7 2548.8 2572.6 2572.6 2576.7 2520.0 2545.5 2552.8 2555.1 2555.4 11377.4 11646.7 11655.3 11667.2 11668.4 6465.6 6682.2 6708.2 6714.8 6718.4 3845.3 4124.1 4200.1 4215.3 4217.9 1077.3 1179.8 1192.4 1238.8 1245.6 14914.7 15247.5 15282.2 15911.6 16031.9 5267.6 5421.6 5568.7 5568.9 5577.2 11599.3 11605.4 11610.0 11619.8 11623.8 6452.6 6464.7 6466.3 6468.3 6468.5 6002.5 6098.6 6190.3 6202.7 6296.6 4175.6 4313.7 4323.4 4370.4 4375.7 1268.7 1270.2 1273.1 1288.1 1290.9 722.7 734.5 737.0 741.7 760.7 4816.0 5595.7 5596.9 5602.8 5683.2 557.3 578.9 580.7 582.2 596.6 3279.9 3676.9 3886.3 3900.1 3904.6 13707.0 14597.8 14598.3 15118.9 15123.7 14340.4 14847.0 14943.6 15036.8 15101.0 8217.6 8352.0 8562.6 8618.8 8639.1 5053.8 6068.8 6301.4 6372.7 6459.7 12958.0 13183.5 13245.9 13645.7 13666.2 13436.3 13618.9 13736.0 13791.7 14112.6 9190.9 9241.7 9434.2 9435.3 9437.5 10309.1 10516.8 10635.0 10642.7 10655.8 8707.7 8762.9 8785.9 8841.2 8906.6 8344.9 8587.8 8623.9 8631.8 8670.2 7889.0 8015.2 8018.5 8043.4 8047.9 5224.6 5283.3 5367.4 5375.9 5502.9 5354.6 5368.7 5410.8 5446.7 5482.1 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:4987.83 Median:5714.77 stddev:1580.23 (27.7%) stddev-sans-first:1546.36 07:23:30 INFO - PID 1638 | Values: 380.1 393.9 405.4 405.7 406.4 5526.9 5874.9 5931.0 5954.0 5978.3 4532.5 4607.5 5780.2 5791.3 5905.2 4286.6 4290.9 4304.4 4314.7 4324.8 4300.2 4313.8 4322.8 4327.0 4344.8 4102.9 4258.9 4267.5 4272.0 4280.0 3145.3 4089.0 4256.6 4261.7 4274.8 3657.3 3694.6 3694.9 3698.1 3714.2 4204.3 5585.2 5702.8 5728.3 5823.3 4179.4 4195.9 4992.2 5008.2 5023.9 5319.9 5934.7 6174.8 6190.7 6221.7 3646.5 3927.6 3929.8 4118.1 4140.5 6319.0 6495.0 6508.0 6545.4 6610.8 4054.2 4184.9 4198.4 4214.8 4215.7 3874.3 4215.5 4237.5 4261.3 4275.6 4568.0 4696.7 4742.6 4883.7 5021.7 5258.5 5671.3 5864.9 6195.8 6276.7 1293.6 1376.6 1433.8 1445.9 1472.2 919.0 963.7 965.7 969.3 978.6 5543.1 5611.4 5906.8 5919.6 5949.9 6332.9 6428.4 6450.5 6452.9 6534.6 5461.6 5808.4 5901.0 5952.2 5988.1 3305.2 3364.9 3410.8 3410.9 3442.3 5408.6 5561.1 5726.7 6674.5 6687.9 5548.7 6583.9 6587.9 6612.5 6657.4 6078.5 6369.2 6510.7 6586.5 6604.1 5947.5 6397.8 6399.0 6412.4 6412.9 6058.5 6229.5 6300.8 6306.3 6330.7 6305.5 6420.4 6455.5 6461.4 6655.6 3542.8 3668.7 4049.7 4113.8 4183.6 5673.7 6181.9 6442.1 6450.1 6465.0 5296.7 5561.0 6198.8 6279.6 6281.5 5318.6 5922.3 6270.7 6279.5 6284.7 5590.4 5850.9 6128.6 6193.3 6222.9 5353.6 5944.1 6267.9 6319.5 6326.4 6021.8 6117.4 6155.1 6193.7 6264.2 07:23:30 INFO - PID 1638 | 07:23:30 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:21623.52 Median:16869.91 stddev:31182.74 (184.8%) stddev-sans-first:31231.08 07:23:31 INFO - PID 1638 | Values: 817.9 834.2 836.3 841.1 855.9 16422.2 16860.8 16863.7 16876.2 16885.2 15217.9 16684.8 17798.5 17959.2 18061.4 1006.7 1029.9 1030.1 1032.0 1034.0 1049.3 1051.9 1052.1 1054.0 1057.4 1044.6 1056.6 1060.8 1062.4 1079.6 1052.4 1056.8 1059.5 1061.6 1081.2 791.8 792.4 795.6 797.6 799.0 16798.7 16921.6 17004.9 17073.0 17283.4 16937.4 17078.9 17235.2 17416.9 17461.0 14091.9 14268.4 14288.7 14458.9 14464.9 3574.8 3594.5 3595.1 3599.1 3603.2 118715.4 118722.4 119238.0 123559.0 125864.0 12981.3 13009.9 13139.8 13168.3 13208.7 25572.9 25579.9 25946.7 25976.1 26006.3 7245.4 7308.6 7323.2 7343.4 7378.4 3873.6 4002.5 4014.0 4021.5 4021.5 4327.8 4389.0 4405.5 4429.3 4466.9 862.8 886.8 887.2 887.6 888.9 643.5 668.3 670.0 680.5 685.4 10990.5 11638.5 11756.0 11868.3 12114.3 618.5 644.4 650.4 658.1 670.7 5028.0 5267.4 5402.7 5660.5 5729.5 105221.5 106013.0 107415.0 108192.4 109008.4 95738.0 96421.0 96628.0 97197.5 98236.5 84838.2 85115.6 86084.0 86097.0 86736.6 10183.9 10411.6 10938.3 11202.9 11286.9 34317.0 37668.4 42056.8 42567.7 43608.3 55687.4 56509.0 56565.7 57304.4 57852.7 14159.9 14297.6 14341.0 14622.9 14635.6 15095.0 15452.6 15591.5 15591.5 15623.0 13750.6 13773.4 13984.6 14106.9 14152.3 13031.3 13418.0 13754.5 13850.1 13886.0 11740.9 12015.4 12020.2 12119.2 12177.9 10393.1 10401.9 10618.6 11038.5 11103.7 13482.1 13787.6 13918.5 13938.8 14002.6 07:23:31 INFO - PID 1638 | -------- Summary: end -------- 07:23:31 INFO - PID 1638 | 07:23:31 INFO - TEST-INFO | 1638: exit 0 07:23:31 INFO - TEST-OK | dromaeo_css | took 1117788ms 07:23:31 INFO - TEST-START | kraken 07:23:31 INFO - operating with platform_type : mac_ 07:23:31 INFO - Initialising browser for kraken test... 07:23:31 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpLtlFRM/profile http://localhost:49221/getInfo.html) 07:23:34 INFO - PID 1649 | __metrics Screen width/height:1600/1200 07:23:34 INFO - PID 1649 | colorDepth:24 07:23:34 INFO - PID 1649 | Browser inner width/height: 1024/691 07:23:34 INFO - PID 1649 | __metrics 07:23:34 INFO - PID 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:23:34 INFO - PID 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:23:35 INFO - TEST-INFO | 1649: exit 0 07:23:35 INFO - Browser initialized. 07:23:35 INFO - Running cycle 1/1 for kraken test... 07:23:35 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpLtlFRM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:23:47 INFO - PID 1650 | RSS: Main: 234553344 07:23:47 INFO - PID 1650 | 07:23:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 07:23:51 INFO - PID 1650 | RSS: Main: 304168960 07:23:51 INFO - PID 1650 | 07:23:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 07:23:54 INFO - PID 1650 | RSS: Main: 287141888 07:23:54 INFO - PID 1650 | 07:23:57 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 07:23:58 INFO - PID 1650 | RSS: Main: 381112320 07:23:58 INFO - PID 1650 | 07:24:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 07:24:00 INFO - PID 1650 | RSS: Main: 283865088 07:24:00 INFO - PID 1650 | 07:24:02 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:24:03 INFO - PID 1650 | RSS: Main: 286121984 07:24:03 INFO - PID 1650 | 07:24:06 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 07:24:06 INFO - PID 1650 | RSS: Main: 349777920 07:24:06 INFO - PID 1650 | 07:24:09 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 07:24:10 INFO - PID 1650 | RSS: Main: 372772864 07:24:10 INFO - PID 1650 | 07:24:13 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 07:24:13 INFO - PID 1650 | RSS: Main: 384303104 07:24:13 INFO - PID 1650 | 07:24:15 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:24:16 INFO - PID 1650 | RSS: Main: 327983104 07:24:16 INFO - PID 1650 | 07:24:17 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:24:18 INFO - PID 1650 | RSS: Main: 327897088 07:24:18 INFO - PID 1650 | 07:24:20 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:24:21 INFO - PID 1650 | RSS: Main: 330264576 07:24:21 INFO - PID 1650 | 07:24:23 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:24:24 INFO - PID 1650 | RSS: Main: 345571328 07:24:24 INFO - PID 1650 | 07:24:27 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:24:28 INFO - PID 1650 | RSS: Main: 338731008 07:24:28 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 07:24:30 INFO - PID 1650 | RSS: Main: 381374464 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | __start_tp_report 07:24:30 INFO - PID 1650 | _x_x_mozilla_page_load 07:24:30 INFO - PID 1650 | _x_x_mozilla_page_load_details 07:24:30 INFO - PID 1650 | |i|pagename|runs| 07:24:30 INFO - PID 1650 | |0;ai-astar;112;111;111;111;111;116;161;162;110;113 07:24:30 INFO - PID 1650 | |1;audio-beat-detection;149;108;109;146;135;137;133;109;136;102 07:24:30 INFO - PID 1650 | |2;audio-dft;185;186;183;184;184;184;183;184;181;182 07:24:30 INFO - PID 1650 | |3;audio-fft;79;59;60;90;64;76;87;81;92;58 07:24:30 INFO - PID 1650 | |4;audio-oscillator;93;71;86;71;70;71;90;70;89;118 07:24:30 INFO - PID 1650 | |5;imaging-gaussian-blur;88;87;87;88;88;87;88;87;89;88 07:24:30 INFO - PID 1650 | |6;imaging-darkroom;95;95;95;94;94;95;94;94;95;94 07:24:30 INFO - PID 1650 | |7;imaging-desaturate;84;84;83;84;84;87;83;83;86;100 07:24:30 INFO - PID 1650 | |8;json-parse-financial;49;48;47;46;47;49;48;63;67;66 07:24:30 INFO - PID 1650 | |9;json-stringify-tinderbox;55;54;59;61;64;59;61;58;58;57 07:24:30 INFO - PID 1650 | |10;stanford-crypto-aes;83;83;82;80;83;82;80;83;80;82 07:24:30 INFO - PID 1650 | |11;stanford-crypto-ccm;138;138;136;136;134;158;151;136;177;153 07:24:30 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;187;191;186;186;178;184;185;181;202;217 07:24:30 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;68;67;69;68;70;70;68;91;68;68 07:24:30 INFO - PID 1650 | __end_tp_report 07:24:30 INFO - PID 1650 | __start_cc_report 07:24:30 INFO - PID 1650 | _x_x_mozilla_cycle_collect,2243 07:24:30 INFO - PID 1650 | __end_cc_report 07:24:30 INFO - PID 1650 | __startTimestamp1494599070487__endTimestamp 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | ------- Summary: start ------- 07:24:30 INFO - PID 1650 | Number of tests: 14 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:121.80 Median:112.50 stddev:20.99 (18.7%) stddev-sans-first:21.96 07:24:30 INFO - PID 1650 | Values: 112.0 111.0 111.0 111.0 111.0 116.0 161.0 162.0 110.0 113.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:135.50 stddev:17.50 (12.9%) stddev-sans-first:16.54 07:24:30 INFO - PID 1650 | Values: 149.0 108.0 109.0 146.0 135.0 137.0 133.0 109.0 136.0 102.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.43 (0.8%) stddev-sans-first:1.42 07:24:30 INFO - PID 1650 | Values: 185.0 186.0 183.0 184.0 184.0 184.0 183.0 184.0 181.0 182.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:74.60 Median:80.00 stddev:13.33 (16.7%) stddev-sans-first:14.05 07:24:30 INFO - PID 1650 | Values: 79.0 59.0 60.0 90.0 64.0 76.0 87.0 81.0 92.0 58.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:78.50 stddev:15.60 (19.9%) stddev-sans-first:16.11 07:24:30 INFO - PID 1650 | Values: 93.0 71.0 86.0 71.0 70.0 71.0 90.0 70.0 89.0 118.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 07:24:30 INFO - PID 1650 | Values: 88.0 87.0 87.0 88.0 88.0 87.0 88.0 87.0 89.0 88.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 07:24:30 INFO - PID 1650 | Values: 95.0 95.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:84.00 stddev:5.16 (6.1%) stddev-sans-first:5.43 07:24:30 INFO - PID 1650 | Values: 84.0 84.0 83.0 84.0 84.0 87.0 83.0 83.0 86.0 100.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:49.00 stddev:8.62 (17.6%) stddev-sans-first:9.02 07:24:30 INFO - PID 1650 | Values: 49.0 48.0 47.0 46.0 47.0 49.0 48.0 63.0 67.0 66.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:2.95 (5.0%) stddev-sans-first:2.83 07:24:30 INFO - PID 1650 | Values: 55.0 54.0 59.0 61.0 64.0 59.0 61.0 58.0 58.0 57.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.50 stddev:1.32 (1.6%) stddev-sans-first:1.32 07:24:30 INFO - PID 1650 | Values: 83.0 83.0 82.0 80.0 83.0 82.0 80.0 83.0 80.0 82.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:144.50 stddev:13.94 (9.7%) stddev-sans-first:14.51 07:24:30 INFO - PID 1650 | Values: 138.0 138.0 136.0 136.0 134.0 158.0 151.0 136.0 177.0 153.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.70 Median:186.50 stddev:11.55 (6.2%) stddev-sans-first:12.21 07:24:30 INFO - PID 1650 | Values: 187.0 191.0 186.0 186.0 178.0 184.0 185.0 181.0 202.0 217.0 07:24:30 INFO - PID 1650 | 07:24:30 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:68.50 stddev:7.20 (10.5%) stddev-sans-first:7.57 07:24:30 INFO - PID 1650 | Values: 68.0 67.0 69.0 68.0 70.0 70.0 68.0 91.0 68.0 68.0 07:24:30 INFO - PID 1650 | -------- Summary: end -------- 07:24:30 INFO - PID 1650 | 07:24:31 INFO - TEST-INFO | 1650: exit 0 07:24:31 INFO - TEST-OK | kraken | took 59798ms 07:24:31 INFO - SUITE-END | took 1177s 07:24:31 INFO - Completed test suite (00:19:37) 07:24:31 INFO - javascript benchmark 07:24:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7552.864585719001, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3452.7410444216675, 3456.7925924444553, 3457.809820459875, 3462.7922324660512, 3479.165000399905, 5885.352611212763, 6015.157877897087, 6055.909161362578, 6059.091136329553, 6097.0, 5448.291722076125, 5527.281453411061, 5956.3150237722675, 6004.069369247763, 6014.518838492921, 5185.533302002819, 5200.479952004807, 5208.869778255555, 5213.608979326546, 5221.973890130544, 5303.071962406582, 5307.575393968493, 5313.840584782462, 5318.228856815759, 5323.121684921984, 5306.204069389584, 5307.442718514559, 5308.23030660554, 5311.309529761145, 5320.600954928396, 5302.257683924254, 5315.867103322428, 5361.973190134025, 5393.352797664305, 5393.7572809223675, 5202.843914682566, 5327.200919862013, 5330.573554115662, 5332.3601167859715, 5362.597805164589, 6182.103594978703, 6187.87624247517, 6189.597676151036, 6192.597481163755, 6195.504359651262, 4925.926111108336, 6190.257169139687, 6281.057841323793, 6351.142595749561, 6379.87240255197, 6363.745450182034, 6412.487001039905, 6440.677966101676, 6453.806385808433, 6487.286398496161, 6186.381361863778, 6199.380061993765, 6199.50403967686, 6208.720607572715, 6220.720067596879, 6411.519136064814, 6502.544821862424, 6623.437007854386, 6637.966810165918, 6648.734050638017, 6259.405356491126, 6260.624362538262, 6264.686765661699, 6271.4669253114, 6293.748250069946, 6278.843028924313, 6279.4034566717, 6401.551891367652, 6475.158229430238, 6483.0, 6281.466075383643, 6313.747450101944, 6317.747290108436, 6353.205849268841, 6362.554621176473, 6274.1843560337775, 6355.777547785805, 6356.713947872357, 6376.936230637727, 6389.9680501597195, 5037.924431133536, 5107.821226257063, 5140.56305214061, 5167.147420675622, 5175.0, 4554.567316104964, 4600.7929643166135, 4662.976685116552, 4667.789949452283, 4688.789004494806, 6191.318954914956, 6199.132121502903, 6201.875962480725, 6211.9068213976825, 6214.658193799294, 6497.122888410098, 6498.54510184292, 6502.512311576556, 6514.478841692653, 6521.347865213441, 6001.879962400728, 6041.124037014608, 6062.393760623991, 6111.92601248044, 6187.102870083812, 5681.289838770153, 5714.228579141763, 5717.885642287214, 5720.227769251181, 5730.34101078373, 6586.901196482152, 6616.999999999904, 6617.239017512951, 6618.768343107968, 6675.23234828001, 6436.195475565554, 6438.323975982455, 6472.190976127984, 6484.708188131451, 6492.220933488012, 6389.616623002635, 6642.83392915181, 6669.066330713801, 6671.666416679243, 6673.366030227121, 6343.4608058313715, 6343.9682801585695, 6360.809175724736, 6372.681365931778, 6390.616563006234, 6430.2926678066315, 6477.449416799436, 6477.902831457626, 6485.448736857231, 6501.414872661483, 6147.600405973598, 6261.843453913689, 6309.968450157719, 6318.431341179132, 6318.747250109944, 6227.4395304421005, 6249.437550620466, 6299.685015749286, 6307.90538141919, 6318.6840657965995, 6260.373962603703, 6305.684715764101, 6307.463865571478, 6324.936750632435, 6326.114343991753, 6244.562880598314, 6297.496200303873, 6303.842403939938, 6308.400701933309, 6331.461825744863, 6104.969475152596, 6173.290071641819, 6251.624902505864, 6276.560640755103, 6292.559520833498], "value": 5915.570999343025, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.3157158557301, 782.4601025292548, 791.1692722641226, 1120.9719757006078, 1130.2088538023386, 25555.872220638917, 35130.4191311391, 35285.0, 35357.46963795545, 35409.82295088521, 37541.62458375416, 37964.35623765844, 37965.29156187971, 38023.479060837606, 38134.809325953334, 5801.6519008859395, 7881.251281128312, 8053.275205231528, 8055.959720201391, 8065.879011814827, 5777.248957635523, 7815.413843961725, 7903.3282171015535, 7907.090684571262, 7910.406719496061, 7943.682252709885, 8001.279884810366, 8006.0, 8013.799655008643, 8018.558979256139, 5751.597388182829, 7944.642491087886, 7980.880286795703, 7990.720324788663, 7996.600169991535, 5761.53907687384, 7728.922710772933, 7812.179721129256, 7822.295993360625, 7825.804354891116, 34296.314073718386, 34592.48111278333, 34649.82675086609, 34656.82671586651, 34666.65333346685, 3491.6159222485508, 3526.576810782697, 3527.276908233806, 3527.7883327000204, 3530.4174811156126, 28004.299892502648, 28065.438691226267, 28118.718812811814, 28138.296542586395, 28167.154985350473, 3022.07826612883, 3063.89276375328, 3080.92167741291, 3115.43922094023, 3116.5792617996735, 297476.0, 297595.0, 297829.0, 298696.0, 298788.0, 45903.0, 46192.076158476644, 46210.0, 46259.76870115628, 46312.84217894579, 47881.0, 47927.5207247923, 47949.28076078861, 48053.798655033905, 48207.0, 13177.670558236121, 13251.939844812392, 13261.0, 13270.402831872396, 13275.070745047755, 8373.8325233495, 8413.116622754646, 10987.285826421257, 10990.450477476094, 11003.889961100447, 9617.807643847083, 9777.022297770167, 10684.611000570032, 11028.503717332578, 11231.943840280746, 4482.596566309048, 5025.221090730943, 5133.512316329943, 5148.819791307286, 5165.199394093921, 848.5587494502824, 957.5403806173076, 977.6675930183629, 993.5975929748463, 1020.6937918624383, 22883.31350059501, 23129.53740925172, 23148.65277020846, 23182.65226021611, 23186.956586953627, 916.8716379706847, 1067.3169171730099, 1078.455380033094, 1120.2998126171144, 1134.8014097532898, 11777.411129443493, 11870.406479675981, 11886.762264754656, 11913.523459061715, 11946.402679865972, 303335.0, 303552.0, 304270.0, 305244.0, 305371.0, 48327.27509087366, 48467.27299090516, 48574.27138592924, 48637.54087377384, 48663.0, 1485.2054151029147, 1489.7616381378928, 1529.6634740357104, 1530.211940850463, 1549.6590750034975, 21588.244411445525, 22747.090116395822, 22755.65866512037, 22758.317250482512, 22777.202797901995, 34017.31965360729, 34139.31721365608, 34140.0, 34229.52327627568, 34235.4864677025, 259438.0, 260342.69828650737, 260542.0, 260606.0, 261261.0, 25214.24357269285, 26717.0, 30807.075787726404, 30809.691903080682, 30833.845830770704, 33181.50227746634, 38666.226675466896, 38815.80592097021, 40233.597664022986, 40433.59566404298, 25335.746642534075, 25590.057183099783, 26460.338491537867, 26716.194799545767, 26784.0, 25595.0, 25599.333743134302, 26378.472430551665, 26989.460210796067, 27008.0, 32057.35885282328, 32867.01398958035, 33225.66774332226, 33643.83178084094, 33799.83100084484, 23112.768872311062, 25329.113481028075, 28418.573721393783, 29007.0, 29482.705172947997, 19072.33246836354, 21253.89373053125, 22266.109355625595, 22624.321270361914, 22784.772152278267], "value": 16068.849516346221, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.8280647187247, 401.5201833808599, 420.9747415155091, 428.79846472158073, 452.7374123008655, 20470.385888423352, 20839.791602083973, 21203.57592848142, 22149.778502214973, 22479.55040899185, 21864.672029919562, 22447.102115915386, 23022.9639666215, 23331.76668233317, 23498.0, 5715.771109211519, 5960.344362120163, 6175.0, 6187.721552530124, 6191.628502289855, 5573.637713548626, 5740.7129643517865, 5859.150423188635, 6111.083337499366, 6183.7217325220245, 4802.59177969873, 6047.304559975593, 6145.078238264251, 6157.538184636171, 6169.118418882239, 5881.794137205198, 6025.517958563326, 6034.064719968434, 6034.396560343975, 6044.909326360108, 5093.261477085839, 5315.176147697113, 5327.920081198785, 5361.651492652965, 5375.569954403638, 22419.0, 26388.60417093745, 26726.5991010135, 26879.596806047924, 27741.612919354153, 2135.4661334666334, 2218.5673793610254, 2221.4335344487054, 2221.733391992971, 2223.1663126327626, 17458.825411745845, 17742.93542387461, 17746.822531774647, 17852.642947141114, 17904.373346932796, 2077.1483691686335, 2111.440468275908, 2133.7012818205462, 2133.775953524873, 2133.9573208535744, 142313.0, 142628.0, 142713.0, 143945.280273599, 144322.0, 10708.94645526783, 11160.883911608937, 11539.711507212387, 12054.51781928713, 12139.757204855854, 13869.09850859691, 16211.51365459038, 16217.59456013609, 16241.431549895697, 16270.105144216945, 7184.1019872515935, 8340.499570025819, 8685.131486851264, 8793.868091978626, 8811.251043661232, 8106.756797296091, 8111.990285750898, 8132.308753755997, 8320.583970801557, 8353.081161072278, 6221.573586301346, 6396.42432181106, 6429.45349645276, 7604.657790399446, 7627.161012288644, 2099.086897199723, 2108.5240331767823, 2113.3342996955907, 2125.861818981761, 2281.6577513373127, 949.349695458613, 952.2810278239884, 959.7858708733457, 982.1798798003747, 993.9055789699967, 20484.487887802625, 21079.26222582202, 21178.046987885584, 21207.78792212089, 21408.929553522263, 910.797746973988, 916.4272329793879, 983.0464449483989, 1009.3439264478149, 1015.081351377005, 10808.081313088476, 10859.34843909368, 11000.889991099986, 11015.228933974706, 11050.723731906766, 512792.0, 520474.0, 523765.0, 532948.0, 537474.0, 81917.0, 103752.48123759333, 116270.4186479079, 129405.0, 134237.0, 1153.5618410302188, 1153.6193056291404, 1197.2218058262026, 1203.9518419263306, 1205.7407657353535, 22284.554308914056, 22398.664020039385, 22480.0, 22686.546269074195, 22810.885945570168, 56756.71621641865, 58091.70954145202, 58476.707616461645, 58514.0, 58996.82006359935, 78222.0, 78832.0, 78910.6054469724, 79648.0, 80836.91630836869, 8726.30189584819, 8745.781355466164, 8878.422902511316, 8904.73285801427, 9017.684381046633, 13796.310184490534, 13917.930410347883, 13947.651308716957, 14335.283235838375, 14343.282835857957, 9649.421034737938, 11236.213465057368, 12021.75956480883, 12034.338111403957, 12058.638240852437, 9395.342326037111, 10340.81080675732, 10492.317999330018, 10513.421761802869, 10858.565657373616, 11745.942865142179, 11910.52357905674, 12032.699182520157, 12126.514939402678, 12156.0, 8647.654093836176, 8794.560271986504, 8801.559922004002, 8804.559772011246, 8811.251043661361, 11574.421278936188, 11767.470463829319, 11816.46825892854, 12045.51817927273, 12093.274403535816], "value": 10223.905150411445, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.8440779310379, 1400.285854214351, 1419.0846903747076, 1424.1526291856342, 1427.0581416265263, 11424.314541127527, 11944.402779861015, 11949.34278614678, 11957.342346170957, 11962.10284228682, 11210.775784484305, 11606.941965290162, 11947.283163010225, 11957.162998590102, 11958.581449649264, 2524.154408273226, 2559.2834006478215, 2571.3700143464876, 2572.5498037843395, 2573.935651608706, 2487.4900645367657, 2528.696556413233, 2531.8860651270643, 2532.050481069599, 2537.619357096432, 2472.2570587735604, 2578.8323758955607, 2578.922632321024, 2579.57437022891, 2582.4705935283223, 2545.707243666965, 2548.7833534149527, 2572.5755250383663, 2572.6269690894715, 2576.6601367289, 2519.987400063006, 2545.5036267927762, 2552.8468291902545, 2555.0546297869723, 2555.4122551813, 11377.431128443544, 11646.708832279177, 11655.30068195911, 11667.183297169202, 11668.35824029678, 6465.644389558573, 6682.198136223636, 6708.228553716336, 6714.83212919676, 6718.361755633207, 3845.3462911305146, 4124.072083781155, 4200.075983283673, 4215.3044747616605, 4217.924429270547, 1077.348204336373, 1179.799434096197, 1192.439553409896, 1238.7708273969345, 1245.5951815659855, 14914.701705965928, 15247.542573722805, 15282.235888205545, 15911.60220994473, 16031.91984040084, 5267.578593712494, 5421.566274698014, 5568.6658800472105, 5568.860778480489, 5577.191307260425, 11599.304041757521, 11605.419729013516, 11610.0, 11619.76760464786, 11623.825642615368, 6452.580582262138, 6464.676766161673, 6466.321036291216, 6468.2561505427475, 6468.482521398277, 6002.519798416116, 6098.603590766586, 6190.2571691397325, 6202.658853763086, 6296.622202667854, 4175.561566035583, 4313.676474264443, 4323.3947247385395, 4370.431843860278, 4375.671824613103, 1268.6828292926768, 1270.2315099364937, 1273.089740835307, 1288.1112032697408, 1290.8967282617368, 722.692855536395, 734.5482528245197, 737.0455260437745, 741.6514238307986, 760.6577040331873, 4816.0, 5595.7481913313995, 5596.944030559724, 5602.803901863415, 5683.204351390726, 557.3395526301346, 578.8784355285353, 580.7067430947429, 582.2023827356549, 596.5525855608294, 3279.918002049968, 3676.8713095041544, 3886.2810380079777, 3900.083480382108, 3904.648581627667, 13707.0, 14597.78103328451, 14598.34307456167, 15118.924405378122, 15123.697526049418, 14340.426382944564, 14847.0, 14943.626409339853, 15036.849631503765, 15101.0, 8217.589120544068, 8351.95824020876, 8562.614682339185, 8618.827623447496, 8639.092895246034, 5053.772580233825, 6068.817935461943, 6301.369863013662, 6372.745090196526, 6459.6770161490795, 12958.0, 13183.53857614979, 13245.867541324464, 13645.658858528615, 13666.248356340497, 13436.328183590585, 13618.93190534041, 13736.0, 13791.655208619864, 14112.576622701336, 9190.85114360705, 9241.722748317561, 9434.150926416656, 9435.339526233372, 9437.528123593656, 10309.07218350352, 10516.842247366449, 10635.042846143884, 10642.73393165177, 10655.774585922718, 8707.651693932172, 8762.868556971524, 8785.868211976698, 8841.248493877834, 8906.59920303575, 8344.874826877724, 8587.828243434971, 8623.930256738213, 8631.784205394664, 8670.176333248332, 7889.0, 8015.198480151938, 8018.4787988780545, 8043.3565314773505, 8047.919520804951, 5224.555912747459, 5283.286756287852, 5367.35591728995, 5375.865603359947, 5502.880104515266, 5354.598405119709, 5368.731563421891, 5410.78356865721, 5446.727663616724, 5482.122860342485], "value": 5182.9454008002685, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.10674913952215, 393.89364871484696, 405.42024904386733, 405.663299461447, 406.40258819535273, 5526.861828454285, 5874.9118763218585, 5930.970345148274, 5953.970230148843, 5978.312494063184, 4532.52408497108, 4607.470140933794, 5780.248567686205, 5791.333996590393, 5905.228431470563, 4286.57134286572, 4290.854572713643, 4304.3643415502065, 4314.71954322968, 4324.783760811971, 4300.225959327336, 4313.805878735465, 4322.762248076355, 4326.961529232977, 4344.826206951718, 4102.917941641165, 4258.893527661818, 4267.46371306331, 4272.0, 4280.0, 3145.3394787094508, 4088.998195442106, 4256.553061928515, 4261.74429534229, 4274.807633656493, 3657.250263695936, 3694.649008344203, 3694.9261014779822, 3698.112453011258, 3714.2014466889477, 4204.285271503852, 5585.2459917910965, 5702.800401985952, 5728.25532680753, 5823.272090988627, 4179.373094035888, 4195.895102622429, 4992.176290911995, 5008.24876268559, 5023.899522009539, 5319.8562309103345, 5934.673592952386, 6174.783882564134, 6190.747345181399, 6221.657808820513, 3646.5441819772527, 3927.6465118139504, 3929.8231579578987, 4118.114605359832, 4140.523839758436, 6319.0, 6494.993276042221, 6507.967460162668, 6545.410913017851, 6610.8016759497295, 4054.2499637566557, 4184.916301673949, 4198.412222288882, 4214.840918747308, 4215.662746980234, 3874.2638898609175, 4215.494140703136, 4237.533871274158, 4261.27558315081, 4275.615194632436, 4567.977160114245, 4696.694714843524, 4742.644301677388, 4883.658143929962, 5021.744563859035, 5258.526732594085, 5671.262735844314, 5864.9472684560915, 6195.845103872439, 6276.748930042839, 1293.5666551705285, 1376.5526203983745, 1433.7849322601694, 1445.9060161089496, 1472.168224952898, 918.9770255743526, 963.7253382785873, 965.6571916969515, 969.2924165359327, 978.5841017567506, 5543.113101903676, 5611.354694210137, 5906.793262235801, 5919.585629006015, 5949.940500595025, 6332.873342533124, 6428.3893030163, 6450.451711604473, 6452.903206451907, 6534.607923524603, 5461.644993075437, 5808.3901190375245, 5900.970495147582, 5952.196453478811, 5988.071848863433, 3305.1905880002287, 3364.9327013459592, 3410.7612467127065, 3410.9488357674654, 3442.277121804399, 5408.621396502207, 5561.138023606348, 5726.742296596663, 6674.532782705164, 6687.933120668828, 5548.69482178484, 6583.9012414813815, 6587.934120658732, 6612.471002319803, 6657.434118100015, 6078.45293923549, 6369.235691717024, 6510.707018184193, 6586.473082153416, 6604.075429439787, 5947.494462970696, 6397.840053998687, 6398.968005159944, 6412.422881940648, 6412.871742565216, 6058.485028772604, 6229.536059026034, 6300.842478938063, 6306.306306306395, 6330.746770129143, 6305.495560355069, 6420.390062944013, 6455.483561315177, 6461.418472337512, 6655.633940133339, 3542.8228588570983, 3668.743187976816, 4049.676025917978, 4113.814878330422, 4183.640316897082, 5673.659580425187, 6181.907271390843, 6442.130312407952, 6450.097221319628, 6464.967675161594, 5296.655717378358, 5561.0, 6198.81403557894, 6279.645133075097, 6281.4660753834605, 5318.61715953847, 5922.2597175353085, 6270.6864656766065, 6279.497640188865, 6284.685765711788, 5590.357108932525, 5850.882982340244, 6128.63228206309, 6193.256809182927, 6222.875542489215, 5353.625246232727, 5944.138099975566, 6267.9373206267355, 6319.494440444845, 6326.398992095744, 6021.789237376671, 6117.3576774439825, 6155.091921504348, 6193.690315484298, 6264.154339164154], "value": 4501.412885701977, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.8691409374501, 834.2033358142972, 836.3267569606464, 841.1084250694264, 855.9272461840743, 16422.178891055446, 16860.83139168611, 16863.662726745457, 16876.156192190403, 16885.24016419261, 15217.858660600445, 16684.83315166851, 17798.466046018606, 17959.19183636736, 18061.36785212518, 1006.6929586476122, 1029.9382037077762, 1030.1398332392423, 1031.9638812641558, 1033.9896601034006, 1049.2655141401049, 1051.8579991701138, 1052.1319911073394, 1053.9934362683618, 1057.3550134417999, 1044.6448207609412, 1056.5773690523774, 1060.787842431513, 1062.4315990944847, 1079.6221322537128, 1052.4106500359824, 1056.804491169136, 1059.475559598, 1061.6496556136456, 1081.1512962324578, 791.76247125862, 792.3898598079446, 795.586295126531, 797.5653268968426, 798.9880151797728, 16798.65610751137, 16921.57696057596, 17004.914975425167, 17073.0, 17283.395081172224, 16937.407190748265, 17078.914605426893, 17235.22441490136, 17416.912915435467, 17461.0, 14091.929540352232, 14268.35913869908, 14288.714225715532, 14458.855411445857, 14464.927675361661, 3574.803385813805, 3594.5327107476123, 3595.1191957970204, 3599.0822340303057, 3603.1712706077487, 118715.4064229682, 118722.40638796837, 119238.0, 123559.0, 125864.0, 12981.286029268293, 13009.869901301056, 13139.802902956464, 13168.341582920815, 13208.669783255495, 25572.872135639576, 25579.87210063938, 25946.74053259443, 25976.090836820622, 26006.34984125412, 7245.420366370678, 7308.59802710856, 7323.157836848724, 7343.449241306924, 7378.446616503784, 3873.6320049595242, 4002.45966794479, 4014.016565941363, 4021.4571032910767, 4021.4772079630047, 4327.826886924488, 4388.978055109704, 4405.493368262691, 4429.335599660077, 4466.932996005063, 862.8015556422058, 886.7517095213351, 887.1660638999319, 887.60057973912, 888.8666699995052, 643.5045015338256, 668.3350066683583, 670.0, 680.5270337115658, 685.372883811317, 10990.505427255792, 11638.476268567934, 11756.0, 11868.287902725864, 12114.273143611412, 618.5051958433326, 644.3813938618978, 650.3788881618043, 658.06554692337, 670.7484693240035, 5028.03561939099, 5267.446918073624, 5402.740217245153, 5660.462256085665, 5729.541636669056, 105221.47389263005, 106013.0, 107415.0, 108192.37711434177, 109008.36487452695, 95738.0, 96421.0, 96628.0337196633, 97197.5420368695, 98236.50881745688, 84838.18228179746, 85115.5744221275, 86084.0, 86097.0, 86736.56631716927, 10183.949080254552, 10411.630688897025, 10938.265433641589, 11202.94398528002, 11286.887131128584, 34316.97049088532, 37668.43497347597, 42056.78971605122, 42567.722968311, 43608.34587481127, 55687.44312556823, 56509.0, 56565.71717141553, 57304.42695573158, 57852.710736446046, 14159.858401416266, 14297.571072867831, 14341.0, 14622.853771462575, 14635.634109147357, 15095.0, 15452.613684657525, 15591.454299099478, 15591.532254032398, 15623.0, 13750.587482375544, 13773.38019789132, 13984.580462586138, 14106.929465352607, 14152.292385380895, 13031.283279419724, 13418.0, 13754.518591849237, 13850.09974351664, 13886.02797804144, 11740.882591173979, 12015.399230038638, 12020.218685785396, 12119.212251203644, 12177.878221217674, 10393.06462418386, 10401.895981040092, 10618.575256989634, 11038.503267352828, 11103.72240693989, 13482.123661961941, 13787.576347372631, 13918.51285205013, 13938.79091813644, 14002.649933751738], "value": 8186.819409429933, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 111.0, 111.0, 111.0, 116.0, 161.0, 162.0, 110.0, 113.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 108.0, 109.0, 146.0, 135.0, 137.0, 133.0, 109.0, 136.0, 102.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 184.0, 184.0, 184.0, 183.0, 184.0, 181.0, 182.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 60.0, 90.0, 64.0, 76.0, 87.0, 81.0, 92.0, 58.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 86.0, 71.0, 70.0, 71.0, 90.0, 70.0, 89.0, 118.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 88.0, 88.0, 87.0, 88.0, 87.0, 89.0, 88.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 84.0, 87.0, 83.0, 83.0, 86.0, 100.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 47.0, 46.0, 47.0, 49.0, 48.0, 63.0, 67.0, 66.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 59.0, 61.0, 64.0, 59.0, 61.0, 58.0, 58.0, 57.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 80.0, 83.0, 82.0, 80.0, 83.0, 80.0, 82.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 136.0, 136.0, 134.0, 158.0, 151.0, 136.0, 177.0, 153.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 191.0, 186.0, 186.0, 178.0, 184.0, 185.0, 181.0, 202.0, 217.0], "value": 189.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 68.0, 70.0, 70.0, 68.0, 91.0, 68.0, 68.0], "value": 70.7, "unit": "score"}]}]} 07:24:31 INFO - Return code: 0 07:24:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:24:31 INFO - # TBPL SUCCESS # 07:24:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:24:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:24:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:24:31 INFO - 2017-05-12 07:24:31.800 screenresolution[1655:9783] starting screenresolution argv=screenresolution get 07:24:31 INFO - 2017-05-12 07:24:31.803 screenresolution[1655:9783] Display 0: 1600x1200x32@60 07:24:31 INFO - 2017-05-12 07:24:31.810 screenresolution[1656:9786] starting screenresolution argv=screenresolution list 07:24:31 INFO - Available Modes on Display 0 07:24:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:24:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:24:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:24:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:24:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:24:32 INFO - Intel Iris: 07:24:32 INFO - Chipset Model: Intel Iris 07:24:32 INFO - Type: GPU 07:24:32 INFO - Bus: Built-In 07:24:32 INFO - VRAM (Dynamic, Max): 1536 MB 07:24:32 INFO - Vendor: Intel (0x8086) 07:24:32 INFO - Device ID: 0x0a2e 07:24:32 INFO - Revision ID: 0x0009 07:24:32 INFO - Displays: 07:24:32 INFO - Display: 07:24:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:24:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:24:32 INFO - Display Serial Number: 1600x1200 60 07:24:32 INFO - Main Display: Yes 07:24:32 INFO - Mirror: Off 07:24:32 INFO - Online: Yes 07:24:32 INFO - Rotation: Supported 07:24:32 INFO - Return code: 0 07:24:32 INFO - Running post-action listener: _resource_record_post_action 07:24:32 INFO - [mozharness: 2017-05-12 14:24:32.056634Z] Finished run-tests step (success) 07:24:32 INFO - Running post-run listener: _resource_record_post_run 07:24:32 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 38696448; Write bytes: 531961856; Read time: 252; Write time: 1593 07:24:32 INFO - TinderboxPrint: CPU usage
53.5% 07:24:32 INFO - TinderboxPrint: I/O read bytes / time
38,696,448 / 252 07:24:32 INFO - TinderboxPrint: I/O write bytes / time
531,961,856 / 1,593 07:24:32 INFO - TinderboxPrint: CPU idle
2,231.1 (46.5%) 07:24:32 INFO - TinderboxPrint: CPU system
962.8 (20.1%) 07:24:32 INFO - TinderboxPrint: CPU user
1,603.7 (33.4%) 07:24:32 INFO - TinderboxPrint: Swap in / out
252,919,808 / 0 07:24:32 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206293504; Write bytes: 208332800; Read time: 15803; Write time: 440 07:24:32 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37725696; Write bytes: 313729024; Read time: 239; Write time: 1114 07:24:32 INFO - Running post-run listener: _upload_blobber_files 07:24:32 INFO - Blob upload gear active. 07:24:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:24:32 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:24:32 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:24:32 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:24:32 INFO - (blobuploader) - INFO - Open directory for files ... 07:24:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:24:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:34 INFO - (blobuploader) - INFO - Done attempting. 07:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:24:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:34 INFO - (blobuploader) - INFO - Done attempting. 07:24:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:24:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:24:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:36 INFO - (blobuploader) - INFO - Done attempting. 07:24:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:24:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:24:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:38 INFO - (blobuploader) - INFO - Done attempting. 07:24:38 INFO - (blobuploader) - INFO - Iteration through files over. 07:24:38 INFO - Return code: 0 07:24:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:24:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:24:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc42ddc565cddf44e44232ab886520193aa9ce01be7b28b0f293d6072a98679fc07f7bbc64aed510948fda476bdec08da954a003d89f57d8e1a93dfd2ed21c9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f2830cae9d90707a436eac9a68ebbaf47e4af2409e0cdcec9e1730d1b3594e085c513a3509c720488385898b9c3d9a41924dad60943c223f022181800014c38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b648722959223a7e5e167c28b015fe5e51b2b31b8489119e6c1121d57fa28e70a92d58a8ef7ee6a8aab4984ac5d773cb1bc9abff542aab7b2e0834dc6ed72be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b945521dc5345c45813667707b9f2c52dad182f65cd4f778b5641d9706d202979a2d8106d9e83fd52cf161757c962e1f7111171edc76bac87f9d1c02de210c8a"} 07:24:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:24:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:24:38 INFO - Contents: 07:24:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc42ddc565cddf44e44232ab886520193aa9ce01be7b28b0f293d6072a98679fc07f7bbc64aed510948fda476bdec08da954a003d89f57d8e1a93dfd2ed21c9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f2830cae9d90707a436eac9a68ebbaf47e4af2409e0cdcec9e1730d1b3594e085c513a3509c720488385898b9c3d9a41924dad60943c223f022181800014c38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b648722959223a7e5e167c28b015fe5e51b2b31b8489119e6c1121d57fa28e70a92d58a8ef7ee6a8aab4984ac5d773cb1bc9abff542aab7b2e0834dc6ed72be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b945521dc5345c45813667707b9f2c52dad182f65cd4f778b5641d9706d202979a2d8106d9e83fd52cf161757c962e1f7111171edc76bac87f9d1c02de210c8a"} 07:24:38 INFO - Running post-run listener: copy_logs_to_upload_dir 07:24:38 INFO - Copying logs to upload dir... 07:24:38 INFO - mkdir: /builds/slave/test/build/upload/logs 07:24:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.688516 ========= master_lag: 1.39 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-12 07:24:39.441678) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:24:39.447360) ========= 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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc42ddc565cddf44e44232ab886520193aa9ce01be7b28b0f293d6072a98679fc07f7bbc64aed510948fda476bdec08da954a003d89f57d8e1a93dfd2ed21c9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f2830cae9d90707a436eac9a68ebbaf47e4af2409e0cdcec9e1730d1b3594e085c513a3509c720488385898b9c3d9a41924dad60943c223f022181800014c38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b648722959223a7e5e167c28b015fe5e51b2b31b8489119e6c1121d57fa28e70a92d58a8ef7ee6a8aab4984ac5d773cb1bc9abff542aab7b2e0834dc6ed72be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b945521dc5345c45813667707b9f2c52dad182f65cd4f778b5641d9706d202979a2d8106d9e83fd52cf161757c962e1f7111171edc76bac87f9d1c02de210c8a"} 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.010954 build_url: 'https://queue.taskcluster.net/v1/task/RhC-X0LeQPWVeQaPIwCr_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc42ddc565cddf44e44232ab886520193aa9ce01be7b28b0f293d6072a98679fc07f7bbc64aed510948fda476bdec08da954a003d89f57d8e1a93dfd2ed21c9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f2830cae9d90707a436eac9a68ebbaf47e4af2409e0cdcec9e1730d1b3594e085c513a3509c720488385898b9c3d9a41924dad60943c223f022181800014c38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1b648722959223a7e5e167c28b015fe5e51b2b31b8489119e6c1121d57fa28e70a92d58a8ef7ee6a8aab4984ac5d773cb1bc9abff542aab7b2e0834dc6ed72be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b945521dc5345c45813667707b9f2c52dad182f65cd4f778b5641d9706d202979a2d8106d9e83fd52cf161757c962e1f7111171edc76bac87f9d1c02de210c8a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-12 07:24:39.485132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-05-12 07:24:39.485475) ========= 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.JtTQl4Q3e1/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.gvimQhlFWk/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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.004945 ========= master_lag: 3.89 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-05-12 07:24:43.375639) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:24:43.376009) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-12 07:24:43.380795) ========= ========= Total master_lag: 5.37 =========