builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0068 starttime: 1494980430.87 results: success (0) buildid: 20170516161920 builduid: 094529f6c45548deb3ee8796dabbad76 revision: 7983fa68e19053f1b9a46d7b38f84916ba67716c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:30.871765) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:30.872613) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:30.872898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:31.163485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:31.163790) ========= 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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-16 17:20:31-- 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% 8.12M=0.001s 2017-05-16 17:20:31 (8.12 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.488372 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:31.683075) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:31.683422) ========= 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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.021647 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:31.733190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 17:20:31.733566) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7983fa68e19053f1b9a46d7b38f84916ba67716c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7983fa68e19053f1b9a46d7b38f84916ba67716c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JwyEuF3zIx/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.SP8FqJhUz4/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-16 17:20:31,809 truncating revision to first 12 chars 2017-05-16 17:20:31,809 Setting DEBUG logging. 2017-05-16 17:20:31,809 attempt 1/10 2017-05-16 17:20:31,809 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7983fa68e190?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 17:20:33,626 unpacking tar archive at: mozilla-beta-7983fa68e190/testing/mozharness/ program finished with exit code 0 elapsedTime=2.090592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 17:20:33.848336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:33.848701) ========= script_repo_revision: 7983fa68e19053f1b9a46d7b38f84916ba67716c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:33.849371) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:33.849963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 17:20:33.873108) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-16 17:20:33.873529) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JwyEuF3zIx/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.SP8FqJhUz4/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:20:34 INFO - MultiFileLogger online at 20170516 17:20:34 in /builds/slave/test 17:20:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:20:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:34 INFO - {'append_to_log': False, 17:20:34 INFO - 'base_work_dir': '/builds/slave/test', 17:20:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:20:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:34 INFO - 'branch': 'Mozilla-Beta', 17:20:34 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:34 INFO - 'config_files': ('talos/mac_config.py',), 17:20:34 INFO - 'default_actions': ('clobber', 17:20:34 INFO - 'read-buildbot-config', 17:20:34 INFO - 'download-and-extract', 17:20:34 INFO - 'populate-webroot', 17:20:34 INFO - 'create-virtualenv', 17:20:34 INFO - 'install', 17:20:34 INFO - 'run-tests'), 17:20:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:34 INFO - 'download_minidump_stackwalk': True, 17:20:34 INFO - 'download_symbols': 'ondemand', 17:20:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:34 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:34 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:34 INFO - 'gecko_profile': False, 17:20:34 INFO - 'gecko_profile_interval': 0, 17:20:34 INFO - 'installer_path': 'installer.exe', 17:20:34 INFO - 'log_level': 'info', 17:20:34 INFO - 'log_name': 'talos', 17:20:34 INFO - 'log_to_console': True, 17:20:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:20:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:20:34 INFO - 'opt_config_files': (), 17:20:34 INFO - 'pip_index': False, 17:20:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:20:34 INFO - 'cmd': ('bash', 17:20:34 INFO - '-c', 17:20:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:20:34 INFO - 'enabled': True, 17:20:34 INFO - 'halt_on_failure': False, 17:20:34 INFO - 'name': 'check_screen_resolution'},), 17:20:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:20:34 INFO - 'cmd': ('bash', 17:20:34 INFO - '-c', 17:20:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:20:34 INFO - 'enabled': True, 17:20:34 INFO - 'halt_on_failure': False, 17:20:34 INFO - 'name': 'check_screen_resolution'},), 17:20:34 INFO - 'run_cmd_checks_enabled': True, 17:20:34 INFO - 'suite': 'dromaeojs', 17:20:34 INFO - 'system_bits': '32', 17:20:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:34 INFO - 'title': 't-yosemite-r7-0068', 17:20:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:34 INFO - 'use_talos_json': True, 17:20:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:34 INFO - 'work_dir': 'build'} 17:20:34 INFO - [mozharness: 2017-05-17 00:20:34.076286Z] Running clobber step. 17:20:34 INFO - Running pre-action listener: _resource_record_pre_action 17:20:34 INFO - Running main action method: clobber 17:20:34 INFO - rmtree: /builds/slave/test/build 17:20:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:35 INFO - Running post-action listener: _resource_record_post_action 17:20:35 INFO - [mozharness: 2017-05-17 00:20:35.156672Z] Finished clobber step (success) 17:20:35 INFO - [mozharness: 2017-05-17 00:20:35.156839Z] Running read-buildbot-config step. 17:20:35 INFO - Running pre-action listener: _resource_record_pre_action 17:20:35 INFO - Running main action method: read_buildbot_config 17:20:35 INFO - Using buildbot properties: 17:20:35 INFO - { 17:20:35 INFO - "project": "", 17:20:35 INFO - "product": "firefox", 17:20:35 INFO - "script_repo_revision": "production", 17:20:35 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 17:20:35 INFO - "repository": "", 17:20:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 17:20:35 INFO - "buildid": "20170516161920", 17:20:35 INFO - "pgo_build": "False", 17:20:35 INFO - "basedir": "/builds/slave/test", 17:20:35 INFO - "buildnumber": 10, 17:20:35 INFO - "slavename": "t-yosemite-r7-0068", 17:20:35 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:20:35 INFO - "platform": "yosemite_r7", 17:20:35 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:20:35 INFO - "branch": "mozilla-beta", 17:20:35 INFO - "repo_path": "releases/mozilla-beta", 17:20:35 INFO - "revision": "7983fa68e19053f1b9a46d7b38f84916ba67716c", 17:20:35 INFO - "stage_platform": "macosx64", 17:20:35 INFO - "builduid": "094529f6c45548deb3ee8796dabbad76", 17:20:35 INFO - "slavebuilddir": "test" 17:20:35 INFO - } 17:20:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:20:35 INFO - Running post-action listener: _resource_record_post_action 17:20:35 INFO - [mozharness: 2017-05-17 00:20:35.160194Z] Finished read-buildbot-config step (success) 17:20:35 INFO - [mozharness: 2017-05-17 00:20:35.160307Z] Running download-and-extract step. 17:20:35 INFO - Running pre-action listener: _resource_record_pre_action 17:20:35 INFO - Running main action method: download_and_extract 17:20:35 INFO - mkdir: /builds/slave/test/build/tests 17:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:35 INFO - trying https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:20:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 17:20:36 INFO - Downloaded 1287 bytes. 17:20:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:20:36 INFO - Using the following test package requirements: 17:20:36 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:20:36 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:20:36 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:20:36 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:20:36 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:20:36 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:20:36 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:20:36 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:20:36 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:20:36 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:20:36 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:20:36 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:20:36 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:20:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:20:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:20:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:20:38 INFO - Expected file size: 51226835 17:20:38 INFO - Obtained file size: 51226835 17:20:54 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:20:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:20:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:20:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:20:56 INFO - Expected file size: 51226835 17:20:56 INFO - Obtained file size: 51226835 17:21:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:21:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:21:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:21:12 INFO - Expected file size: 14404449 17:21:12 INFO - Obtained file size: 14404449 17:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:13 INFO - trying https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:21:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:21:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 17:21:14 INFO - Downloaded 59542578 bytes. 17:21:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:21:14 INFO - mkdir: /builds/slave/test/properties 17:21:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:21:14 INFO - Writing to file /builds/slave/test/properties/build_url 17:21:14 INFO - Contents: 17:21:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:21:15 INFO - Running post-action listener: _resource_record_post_action 17:21:15 INFO - Running post-action listener: set_extra_try_arguments 17:21:15 INFO - [mozharness: 2017-05-17 00:21:15.576349Z] Finished download-and-extract step (success) 17:21:15 INFO - [mozharness: 2017-05-17 00:21:15.576460Z] Running populate-webroot step. 17:21:15 INFO - Running pre-action listener: _resource_record_pre_action 17:21:15 INFO - Running main action method: populate_webroot 17:21:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:21:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:21:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:21:15 INFO - '--tppagecycles', 17:21:15 INFO - '7'], 17:21:15 INFO - 'tests': ['tsvgm']}}, 17:21:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:21:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:21:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:21:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:21:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:21:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:21:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['damp', 'tps']}, 17:21:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'tests': ['damp', 'tps']}, 17:21:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['dromaeo_dom']}, 17:21:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:21:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['basic_compositor_video']}, 17:21:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:21:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['a11yr', 17:21:15 INFO - 'ts_paint', 17:21:15 INFO - 'tpaint', 17:21:15 INFO - 'sessionrestore', 17:21:15 INFO - 'sessionrestore_no_auto_restore', 17:21:15 INFO - 'tabpaint']}, 17:21:15 INFO - 'other-e10s': {'tests': ['a11yr', 17:21:15 INFO - 'ts_paint', 17:21:15 INFO - 'tpaint', 17:21:15 INFO - 'sessionrestore', 17:21:15 INFO - 'sessionrestore_no_auto_restore', 17:21:15 INFO - 'tabpaint']}, 17:21:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:21:15 INFO - 'ts_paint', 17:21:15 INFO - 'tpaint', 17:21:15 INFO - 'sessionrestore', 17:21:15 INFO - 'sessionrestore_no_auto_restore', 17:21:15 INFO - 'tabpaint']}, 17:21:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:21:15 INFO - 'ts_paint', 17:21:15 INFO - 'tpaint', 17:21:15 INFO - 'sessionrestore', 17:21:15 INFO - 'sessionrestore_no_auto_restore', 17:21:15 INFO - 'tabpaint']}, 17:21:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['a11yr', 17:21:15 INFO - 'ts_paint', 17:21:15 INFO - 'tpaint', 17:21:15 INFO - 'sessionrestore', 17:21:15 INFO - 'sessionrestore_no_auto_restore', 17:21:15 INFO - 'tabpaint']}, 17:21:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['a11yr', 17:21:15 INFO - 'ts_paint', 17:21:15 INFO - 'tpaint', 17:21:15 INFO - 'sessionrestore', 17:21:15 INFO - 'sessionrestore_no_auto_restore', 17:21:15 INFO - 'tabpaint']}, 17:21:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['tsvgx', 17:21:15 INFO - 'tsvgr_opacity', 17:21:15 INFO - 'tart', 17:21:15 INFO - 'tscrollx', 17:21:15 INFO - 'cart', 17:21:15 INFO - 'tsvg_static']}, 17:21:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:21:15 INFO - 'tsvgr_opacity', 17:21:15 INFO - 'tart', 17:21:15 INFO - 'tscrollx', 17:21:15 INFO - 'cart', 17:21:15 INFO - 'tsvg_static']}, 17:21:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'talos_options': ['--disable-e10s'], 17:21:15 INFO - 'tests': ['tp5o']}, 17:21:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:21:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'talos_options': ['--disable-e10s', 17:21:15 INFO - '--xperf_path', 17:21:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:21:15 INFO - 'tests': ['tp5n']}, 17:21:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:21:15 INFO - 'talos_options': ['--xperf_path', 17:21:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:21:15 INFO - 'tests': ['tp5n']}}, 17:21:15 INFO - 'talos.zip': {'path': '', 17:21:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:21:15 INFO - Running post-action listener: _resource_record_post_action 17:21:15 INFO - [mozharness: 2017-05-17 00:21:15.595040Z] Finished populate-webroot step (success) 17:21:15 INFO - [mozharness: 2017-05-17 00:21:15.595182Z] Running create-virtualenv step. 17:21:15 INFO - Running pre-action listener: _resource_record_pre_action 17:21:15 INFO - Running main action method: create_virtualenv 17:21:15 INFO - Creating virtualenv /builds/slave/test/build/venv 17:21:15 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 17:21:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:21:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:21:15 INFO - Using real prefix '/tools/python27' 17:21:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:21:16 INFO - Installing distribute.............................................................................................................................................................................................done. 17:21:19 INFO - Installing pip.................done. 17:21:19 INFO - Return code: 0 17:21:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:21:19 INFO - Reading from file tmpfile_stdout 17:21:19 INFO - Output received: 17:21:19 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) 17:21:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21: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')]} 17:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21: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', 'pip>=1.5'] in /builds/slave/test/build 17:21: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 pip>=1.5 17:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:19 INFO - 'HOME': '/Users/cltbld', 17:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:19 INFO - 'LOGNAME': 'cltbld', 17:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:19 INFO - 'MOZ_NO_REMOTE': '1', 17:21:19 INFO - 'NO_EM_RESTART': '1', 17:21:19 INFO - 'PAGER': '/bin/cat', 17:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:19 INFO - 'PWD': '/builds/slave/test', 17:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:19 INFO - 'SHELL': '/bin/bash', 17:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:19 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:19 INFO - 'USER': 'cltbld', 17:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:19 INFO - 'XPC_FLAGS': '0x0', 17:21:19 INFO - 'XPC_SERVICE_NAME': '0', 17:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:19 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 17:21:19 INFO - Cleaning up... 17:21:19 INFO - Return code: 0 17:21:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21: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')]} 17:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21: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', '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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:21: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 psutil>=3.1.1 17:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:19 INFO - 'HOME': '/Users/cltbld', 17:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:19 INFO - 'LOGNAME': 'cltbld', 17:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:19 INFO - 'MOZ_NO_REMOTE': '1', 17:21:19 INFO - 'NO_EM_RESTART': '1', 17:21:19 INFO - 'PAGER': '/bin/cat', 17:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:19 INFO - 'PWD': '/builds/slave/test', 17:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:19 INFO - 'SHELL': '/bin/bash', 17:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:19 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:19 INFO - 'USER': 'cltbld', 17:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:19 INFO - 'XPC_FLAGS': '0x0', 17:21:19 INFO - 'XPC_SERVICE_NAME': '0', 17:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:20 INFO - Downloading/unpacking psutil>=3.1.1 17:21:20 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 17:21:20 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 17:21:20 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 17:21:20 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 17:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:21:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:21:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:21:22 INFO - Installing collected packages: psutil 17:21:22 INFO - Running setup.py install for psutil 17:21:22 INFO - building 'psutil._psutil_osx' extension 17:21:22 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 17:21:22 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 17:21:22 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 17:21:22 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 17:21:22 INFO - building 'psutil._psutil_posix' extension 17:21:22 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 17:21:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:21:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:21:23 INFO - ^ 17:21:23 INFO - 1 warning generated. 17:21:23 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 17:21:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:21:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:21:23 INFO - Successfully installed psutil 17:21:23 INFO - Cleaning up... 17:21:23 INFO - Return code: 0 17:21:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21: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')]} 17:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:23 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:23 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 17:21:23 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 17:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:23 INFO - 'HOME': '/Users/cltbld', 17:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:23 INFO - 'LOGNAME': 'cltbld', 17:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:23 INFO - 'MOZ_NO_REMOTE': '1', 17:21:23 INFO - 'NO_EM_RESTART': '1', 17:21:23 INFO - 'PAGER': '/bin/cat', 17:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:23 INFO - 'PWD': '/builds/slave/test', 17:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:23 INFO - 'SHELL': '/bin/bash', 17:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:23 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:23 INFO - 'USER': 'cltbld', 17:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:23 INFO - 'XPC_FLAGS': '0x0', 17:21:23 INFO - 'XPC_SERVICE_NAME': '0', 17:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:21:23 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 17:21:23 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 17:21:23 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 17:21:23 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 17:21:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:21:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:21:25 INFO - Installing collected packages: mozsystemmonitor 17:21:25 INFO - Running setup.py install for mozsystemmonitor 17:21:25 INFO - Successfully installed mozsystemmonitor 17:21:25 INFO - Cleaning up... 17:21:25 INFO - Return code: 0 17:21:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:21:25 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')]} 17:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:25 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')]} 17:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:25 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:25 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 17:21:25 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 17:21:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:25 INFO - 'HOME': '/Users/cltbld', 17:21:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:25 INFO - 'LOGNAME': 'cltbld', 17:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:25 INFO - 'MOZ_NO_REMOTE': '1', 17:21:25 INFO - 'NO_EM_RESTART': '1', 17:21:25 INFO - 'PAGER': '/bin/cat', 17:21:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:25 INFO - 'PWD': '/builds/slave/test', 17:21:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:25 INFO - 'SHELL': '/bin/bash', 17:21:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:25 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:25 INFO - 'USER': 'cltbld', 17:21:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:25 INFO - 'XPC_FLAGS': '0x0', 17:21:25 INFO - 'XPC_SERVICE_NAME': '0', 17:21:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:25 INFO - Downloading/unpacking jsonschema==2.5.1 17:21:25 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 17:21:25 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 17:21:25 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 17:21:25 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 17:21:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:21:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:21:26 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 17:21:26 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 17:21:26 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 17:21:26 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 17:21:27 INFO - Downloading functools32-3.2.3-2.tar.gz 17:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:21:27 INFO - warning: no files found matching '*.txt' 17:21:27 INFO - no previously-included directories found matching 'build' 17:21:27 INFO - no previously-included directories found matching 'dist' 17:21:27 INFO - no previously-included directories found matching '.git*' 17:21:27 INFO - Installing collected packages: jsonschema, functools32 17:21:27 INFO - Running setup.py install for functools32 17:21:27 INFO - warning: no files found matching '*.txt' 17:21:27 INFO - no previously-included directories found matching 'build' 17:21:27 INFO - no previously-included directories found matching 'dist' 17:21:27 INFO - no previously-included directories found matching '.git*' 17:21:27 INFO - Successfully installed jsonschema functools32 17:21:27 INFO - Cleaning up... 17:21:27 INFO - Return code: 0 17:21:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21: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')]} 17:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:27 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:27 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 17:21:27 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 17:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:27 INFO - 'HOME': '/Users/cltbld', 17:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:27 INFO - 'LOGNAME': 'cltbld', 17:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:27 INFO - 'MOZ_NO_REMOTE': '1', 17:21:27 INFO - 'NO_EM_RESTART': '1', 17:21:27 INFO - 'PAGER': '/bin/cat', 17:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:27 INFO - 'PWD': '/builds/slave/test', 17:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:27 INFO - 'SHELL': '/bin/bash', 17:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:27 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:27 INFO - 'USER': 'cltbld', 17:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:27 INFO - 'XPC_FLAGS': '0x0', 17:21:27 INFO - 'XPC_SERVICE_NAME': '0', 17:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:21:27 INFO - Cleaning up... 17:21:27 INFO - Return code: 0 17:21:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21: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')]} 17:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:27 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:27 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 17:21:27 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 17:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:27 INFO - 'HOME': '/Users/cltbld', 17:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:27 INFO - 'LOGNAME': 'cltbld', 17:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:27 INFO - 'MOZ_NO_REMOTE': '1', 17:21:27 INFO - 'NO_EM_RESTART': '1', 17:21:27 INFO - 'PAGER': '/bin/cat', 17:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:27 INFO - 'PWD': '/builds/slave/test', 17:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:27 INFO - 'SHELL': '/bin/bash', 17:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:27 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:27 INFO - 'USER': 'cltbld', 17:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:27 INFO - 'XPC_FLAGS': '0x0', 17:21:27 INFO - 'XPC_SERVICE_NAME': '0', 17:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:28 INFO - Downloading/unpacking blobuploader==1.2.4 17:21:28 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 17:21:28 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 17:21:28 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 17:21:28 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 17:21:29 INFO - Downloading blobuploader-1.2.4.tar.gz 17:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:21:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:21: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 17:21: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 17:21: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 17:21: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 17:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:21:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:21:30 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 17:21:30 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 17:21:30 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 17:21:30 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 17:21:30 INFO - Downloading docopt-0.6.1.tar.gz 17:21:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:21:30 INFO - Installing collected packages: blobuploader, requests, docopt 17:21:30 INFO - Running setup.py install for blobuploader 17:21:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:21:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:21:30 INFO - Running setup.py install for requests 17:21:31 INFO - Running setup.py install for docopt 17:21:31 INFO - Successfully installed blobuploader requests docopt 17:21:31 INFO - Cleaning up... 17:21:31 INFO - Return code: 0 17:21:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21: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')]} 17:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21: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')]} 17:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:31 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:31 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 17:21:31 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 17:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:31 INFO - 'HOME': '/Users/cltbld', 17:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:31 INFO - 'LOGNAME': 'cltbld', 17:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:31 INFO - 'MOZ_NO_REMOTE': '1', 17:21:31 INFO - 'NO_EM_RESTART': '1', 17:21:31 INFO - 'PAGER': '/bin/cat', 17:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:31 INFO - 'PWD': '/builds/slave/test', 17:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:31 INFO - 'SHELL': '/bin/bash', 17:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:31 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:31 INFO - 'USER': 'cltbld', 17:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:31 INFO - 'XPC_FLAGS': '0x0', 17:21:31 INFO - 'XPC_SERVICE_NAME': '0', 17:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-R3Yu0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_4Pr8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-JP22Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-KoJatZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qU_1E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-AuG4E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-G6yUNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-WQNMrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-wiR97G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-4VUwd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-hZQdhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-tkccp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-U4eTye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-T3cBiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:33 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0c59Ja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:33 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-uIAi3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:33 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-iuM8ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:21:33 INFO - Running setup.py install for manifestparser 17:21:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:21:33 INFO - Running setup.py install for mozcrash 17:21:33 INFO - Running setup.py install for mozdebug 17:21:33 INFO - Running setup.py install for mozdevice 17:21:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:21:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:21:33 INFO - Running setup.py install for mozfile 17:21:34 INFO - Running setup.py install for mozhttpd 17:21:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Running setup.py install for mozinfo 17:21:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Running setup.py install for mozInstall 17:21:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Running setup.py install for mozleak 17:21:34 INFO - Running setup.py install for mozlog 17:21:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:21:34 INFO - Running setup.py install for moznetwork 17:21:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:21:35 INFO - Running setup.py install for mozprocess 17:21:35 INFO - Running setup.py install for mozprofile 17:21:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:21:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:21:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:21:35 INFO - Running setup.py install for mozrunner 17:21:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:21:35 INFO - Running setup.py install for mozscreenshot 17:21:35 INFO - Running setup.py install for moztest 17:21:35 INFO - Running setup.py install for mozversion 17:21:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:21:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:35 INFO - Cleaning up... 17:21:35 INFO - Return code: 0 17:21:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:35 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:35 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 17:21:35 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 17:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:35 INFO - 'HOME': '/Users/cltbld', 17:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:35 INFO - 'LOGNAME': 'cltbld', 17:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:35 INFO - 'MOZ_NO_REMOTE': '1', 17:21:35 INFO - 'NO_EM_RESTART': '1', 17:21:35 INFO - 'PAGER': '/bin/cat', 17:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:35 INFO - 'PWD': '/builds/slave/test', 17:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:35 INFO - 'SHELL': '/bin/bash', 17:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:35 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:35 INFO - 'USER': 'cltbld', 17:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:35 INFO - 'XPC_FLAGS': '0x0', 17:21:35 INFO - 'XPC_SERVICE_NAME': '0', 17:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Mn5T32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:21:36 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yK_ipA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:21:36 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-5BMnHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:21:36 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yjgv4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-imXtD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:21:36 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-CiE_yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:21:36 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ISZRqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:21:36 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)) 17:21:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:21:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-PqkGbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-JeK2KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Az_MDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-PLPvAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-BFx9Q1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-cmgQvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-X4wrEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-uTHREz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-FqLeD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:21:37 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)) 17:21:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:21:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Jn0M_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:21:38 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)) 17:21:38 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)) 17:21:38 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)) 17:21:38 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)) 17:21:38 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:38 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:38 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 17:21:38 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 17:21:38 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 17:21:38 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 17:21:39 INFO - Downloading blessings-1.6.tar.gz 17:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:39 INFO - Installing collected packages: blessings 17:21:39 INFO - Running setup.py install for blessings 17:21:39 INFO - Successfully installed blessings 17:21:39 INFO - Cleaning up... 17:21:39 INFO - Return code: 0 17:21:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:21:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:40 INFO - Reading from file tmpfile_stdout 17:21:40 INFO - Current package versions: 17:21:40 INFO - blessings == 1.6 17:21:40 INFO - blobuploader == 1.2.4 17:21:40 INFO - docopt == 0.6.1 17:21:40 INFO - functools32 == 3.2.3-2 17:21:40 INFO - jsonschema == 2.5.1 17:21:40 INFO - manifestparser == 1.1 17:21:40 INFO - mozInstall == 1.12 17:21:40 INFO - mozcrash == 1.0 17:21:40 INFO - mozdebug == 0.1 17:21:40 INFO - mozdevice == 0.49 17:21:40 INFO - mozfile == 1.2 17:21:40 INFO - mozhttpd == 0.7 17:21:40 INFO - mozinfo == 0.9 17:21:40 INFO - mozleak == 0.1 17:21:40 INFO - mozlog == 3.4 17:21:40 INFO - moznetwork == 0.27 17:21:40 INFO - mozprocess == 0.25 17:21:40 INFO - mozprofile == 0.28 17:21:40 INFO - mozrunner == 6.13 17:21:40 INFO - mozscreenshot == 0.1 17:21:40 INFO - mozsystemmonitor == 0.3 17:21:40 INFO - moztest == 0.8 17:21:40 INFO - mozversion == 1.4 17:21:40 INFO - psutil == 3.1.1 17:21:40 INFO - requests == 1.2.3 17:21:40 INFO - wsgiref == 0.1.2 17:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:40 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:40 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 17:21:40 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 17:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:40 INFO - 'HOME': '/Users/cltbld', 17:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:40 INFO - 'LOGNAME': 'cltbld', 17:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:40 INFO - 'MOZ_NO_REMOTE': '1', 17:21:40 INFO - 'NO_EM_RESTART': '1', 17:21:40 INFO - 'PAGER': '/bin/cat', 17:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:40 INFO - 'PWD': '/builds/slave/test', 17:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:40 INFO - 'SHELL': '/bin/bash', 17:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:40 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:40 INFO - 'USER': 'cltbld', 17:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:40 INFO - 'XPC_FLAGS': '0x0', 17:21:40 INFO - 'XPC_SERVICE_NAME': '0', 17:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 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)) 17:21:40 INFO - Cleaning up... 17:21:40 INFO - Return code: 0 17:21:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:40 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 0x10a934b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a937768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b2da25a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, '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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 17:21:40 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 17:21:40 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 17:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:40 INFO - 'HOME': '/Users/cltbld', 17:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:40 INFO - 'LOGNAME': 'cltbld', 17:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:40 INFO - 'MOZ_NO_REMOTE': '1', 17:21:40 INFO - 'NO_EM_RESTART': '1', 17:21:40 INFO - 'PAGER': '/bin/cat', 17:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:40 INFO - 'PWD': '/builds/slave/test', 17:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:40 INFO - 'SHELL': '/bin/bash', 17:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:40 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:40 INFO - 'USER': 'cltbld', 17:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:40 INFO - 'XPC_FLAGS': '0x0', 17:21:40 INFO - 'XPC_SERVICE_NAME': '0', 17:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:21:40 INFO - Cleaning up... 17:21:40 INFO - Return code: 0 17:21:40 INFO - Running post-action listener: _resource_record_post_action 17:21:40 INFO - Running post-action listener: _start_resource_monitoring 17:21:40 INFO - Starting resource monitoring. 17:21:40 INFO - [mozharness: 2017-05-17 00:21:40.593991Z] Finished create-virtualenv step (success) 17:21:40 INFO - [mozharness: 2017-05-17 00:21:40.595042Z] Running install step. 17:21:40 INFO - Running pre-action listener: _resource_record_pre_action 17:21:40 INFO - Running main action method: install 17:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:21:40 INFO - Reading from file tmpfile_stdout 17:21:40 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:21:40 INFO - Reading from file tmpfile_stdout 17:21:40 INFO - Output received: 17:21:40 INFO - Usage: mozinstall [options] installer 17:21:40 INFO - Options: 17:21:40 INFO - -h, --help show this help message and exit 17:21:40 INFO - -d DEST, --destination=DEST 17:21:40 INFO - Directory to install application into. [default: 17:21:40 INFO - "/builds/slave/test"] 17:21:40 INFO - --app=APP Application being installed. [default: firefox] 17:21:40 INFO - mkdir: /builds/slave/test/build/application 17:21:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:21:55 INFO - Reading from file tmpfile_stdout 17:21:55 INFO - Output received: 17:21:55 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:21:55 INFO - Running post-action listener: _resource_record_post_action 17:21:55 INFO - [mozharness: 2017-05-17 00:21:55.889830Z] Finished install step (success) 17:21:55 INFO - [mozharness: 2017-05-17 00:21:55.889969Z] Running run-tests step. 17:21:55 INFO - Running pre-action listener: _resource_record_pre_action 17:21:55 INFO - Running main action method: run_tests 17:21:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:21:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:21:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:21:55 INFO - 2017-05-16 17:21:55.904 screenresolution[1921:17451] starting screenresolution argv=screenresolution get 17:21:55 INFO - 2017-05-16 17:21:55.907 screenresolution[1921:17451] Display 0: 1600x1200x32@60 17:21:55 INFO - 2017-05-16 17:21:55.913 screenresolution[1922:17453] starting screenresolution argv=screenresolution list 17:21:55 INFO - Available Modes on Display 0 17:21:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:21:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:21:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:21:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:21:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:21:56 INFO - Intel Iris: 17:21:56 INFO - Chipset Model: Intel Iris 17:21:56 INFO - Type: GPU 17:21:56 INFO - Bus: Built-In 17:21:56 INFO - VRAM (Dynamic, Max): 1536 MB 17:21:56 INFO - Vendor: Intel (0x8086) 17:21:56 INFO - Device ID: 0x0a2e 17:21:56 INFO - Revision ID: 0x0009 17:21:56 INFO - Displays: 17:21:56 INFO - Display: 17:21:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:21:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:21:56 INFO - Display Serial Number: 1600x1200 60 17:21:56 INFO - Main Display: Yes 17:21:56 INFO - Mirror: Off 17:21:56 INFO - Online: Yes 17:21:56 INFO - Rotation: Supported 17:21:56 INFO - Return code: 0 17:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:56 INFO - Python 2.7.3 17:21:56 INFO - Return code: 0 17:21:56 INFO - grabbing minidump binary from tooltool 17:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:56 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 0x10a712d30>, '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 0x10a639db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a707be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:56 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 17:21:56 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 17:21:56 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 17:21:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:56 INFO - Return code: 0 17:21:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:21:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:56 INFO - ENV: RUST_BACKTRACE is now 1 17:21:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:21:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 17:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 17:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JwyEuF3zIx/Render', 17:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:21:56 INFO - 'HOME': '/Users/cltbld', 17:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:21:56 INFO - 'LOGNAME': 'cltbld', 17:21:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:56 INFO - 'MOZ_NO_REMOTE': '1', 17:21:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:56 INFO - 'NO_EM_RESTART': '1', 17:21:56 INFO - 'PAGER': '/bin/cat', 17:21:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:56 INFO - 'PWD': '/builds/slave/test', 17:21:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:21:56 INFO - 'RUST_BACKTRACE': '1', 17:21:56 INFO - 'SHELL': '/bin/bash', 17:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SP8FqJhUz4/Listeners', 17:21:56 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 17:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:21:56 INFO - 'USER': 'cltbld', 17:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:56 INFO - 'XPC_FLAGS': '0x0', 17:21:56 INFO - 'XPC_SERVICE_NAME': '0', 17:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:21:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 17:21:56 INFO - mozversion application_buildid: 20170516161920 17:21:56 INFO - mozversion application_changeset: 7983fa68e19053f1b9a46d7b38f84916ba67716c 17:21:56 INFO - mozversion application_display_name: Firefox 17:21:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:56 INFO - mozversion application_name: Firefox 17:21:56 INFO - mozversion application_remotingname: firefox 17:21:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:21:56 INFO - mozversion application_vendor: Mozilla 17:21:56 INFO - mozversion application_version: 54.0 17:21:56 INFO - mozversion platform_buildid: 20170516161920 17:21:56 INFO - mozversion platform_changeset: 7983fa68e19053f1b9a46d7b38f84916ba67716c 17:21:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:21:56 INFO - mozversion platform_version: 54.0 17:21:56 INFO - using testdate: 1494980516 17:21:56 INFO - actual date: 1494980516 17:21:56 INFO - starting webserver on 'localhost:53352' 17:21:56 INFO - SUITE-START | Running 2 tests 17:21:56 INFO - TEST-START | dromaeo_css 17:21:56 INFO - operating with platform_type : mac_ 17:21:56 INFO - Initialising browser for dromaeo_css test... 17:21:56 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpDXCTcU/profile http://localhost:53352/getInfo.html) 17:21:59 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 17:21:59 INFO - PROCESS | 1932 | colorDepth:24 17:21:59 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 17:21:59 INFO - PROCESS | 1932 | __metrics 17:21:59 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:21:59 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:22:00 INFO - TEST-INFO | 1932: exit 0 17:22:00 INFO - Browser initialized. 17:22:00 INFO - Running cycle 1/1 for dromaeo_css test... 17:22:00 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpDXCTcU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:22:12 INFO - PROCESS | 1935 | RSS: Main: 165384192 17:22:12 INFO - PROCESS | 1935 | 17:25:16 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53352/tests/dromaeo/cssquery-ext.html) 17:25:17 INFO - PROCESS | 1935 | RSS: Main: 291741696 17:25:17 INFO - PROCESS | 1935 | 17:28:20 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-ext.html (next: http://localhost:53352/tests/dromaeo/cssquery-jquery.html) 17:28:21 INFO - PROCESS | 1935 | RSS: Main: 306786304 17:28:21 INFO - PROCESS | 1935 | 17:31:23 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53352/tests/dromaeo/cssquery-mootools.html) 17:31:24 INFO - PROCESS | 1935 | RSS: Main: 281030656 17:31:24 INFO - PROCESS | 1935 | 17:34:26 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53352/tests/dromaeo/cssquery-prototype.html) 17:34:27 INFO - PROCESS | 1935 | RSS: Main: 267235328 17:34:27 INFO - PROCESS | 1935 | 17:37:31 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53352/tests/dromaeo/cssquery-yui.html) 17:37:31 INFO - PROCESS | 1935 | RSS: Main: 258191360 17:37:31 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53352/tests/dromaeo/cssquery-yui.html (next: http://localhost:53352/tests/dromaeo/cssquery-dojo.html) 17:40:35 INFO - PROCESS | 1935 | RSS: Main: 337793024 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | __start_tp_report 17:40:35 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 17:40:35 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 17:40:35 INFO - PROCESS | 1935 | |i|pagename|runs| 17:40:35 INFO - PROCESS | 1935 | |0;dojo.html;3167.0182243504514;3341.8162001089936;3441.5698037745265;3481.129717570608;3536.2573859489507;6146.815595532141;6148.3851614838495;6151.323354431009;6169.290531588863;6239.25128984522;6160.507159427246;6182.041783523549;6185;6201.131841542187;6224.813255602327;5449.56403487721;5458.563314934796;5482;5516.310461192351;5581.665100094007;5357.517823395893;5358.4641535846495;5538.169274608801;5589.245451864007;5615.0454422748235;5395.865103372408;5434.619576629638;5455.263539422167;5458.699771512565;5493.258410114643;5525.778968841241;5544.77820887166;5556.277683901067;5559.221708960748;5563.777448902039;5388.353397592275;5492.642978206443;5498.835034948958;5514.145307477307;5526.032944234743;5898.911516327258;5951.315598706117;5984.311804142536;6128.571000029955;6187.4740647045055;6393.264774550893;6413.551051426402;6434.260060093103;6439.969604863246;6453.322401147859;6472.644004579746;6540.345965403421;6550.737970481175;6556.147700798866;6558.2458017328145;6047.244094488189;6199.721012554446;6214.440700336946;6273.0904018917;6274.901892168852;6596.043573681789;6607.405333520006;6615.63614001225;6685.264620891698;6761.087253220752;6420.935790642127;6424.839379015562;6435.324290949477;6440.838979025468;6447.2907980122145;6454.128692626436;6568.540202185896;6572.86854262912;6573.605583664996;6594.9670251649395;6270.968645156745;6318;6322.4942004639515;6499.642519661369;6500.154979852589;6333.968330158412;6365.872682546323;6419.871602567922;6465.353464653497;6503.804885853432;5218.478152184751;5298.947010529922;5302.549283310885;5302.867428314323;5316.574674026068;4675.929861052087;4680.836170734008;4689.577937985598;4700.412448443944;4709.293605959134;6029.397060293935;6086.543509236825;6091.238595175603;6174.598651087665;6270.059491076375;6574.309697481791;6582;6585.703643336062;6588.901166482507;6594.604323740591;6223.782167624111;6257.155284036597;6261.092141639437;6265;6281.905771413432;5614.578906582023;5658.91511627326;5754.827355179351;5839.182514447981;5839.562032847553;6583.572067815672;6731.495137864582;6781.7287308509085;6802.727890884309;6806.251312355538;6587.275399705933;6611.107500487373;6614.371634694697;6624.503162262753;6631.469482441296;6673.132492775908;6780.38976492118;6787.524873258824;6809.182898052265;6811.148606424197;6342.682865856781;6399.200099987502;6421.8073457796345;6424.51816113784;6471.741130354922;5547.805826796042;6507.544471886922;6529.804105876831;6561.179852518435;6574.901376479453;6168.0439531872635;6268.216472940882;6302.117703521419;6339.5562310637815;6373.7131829066875;6445.70994305248;6454.967725161344;6462.676866156579;6465.191851018622;6471.838204044937;6147.631142131487;6191;6248.250209974832;6308.842278943063;6335.524835637438;5295.767364149948;6412.294647588855;6414.230292364946;6418.229812422539;6426.839329016812;6357.300696923242;6381.776637817654;6394.520410969103;6400;6404.7438102477245 17:40:35 INFO - PROCESS | 1935 | |1;ext.html;773.9845203095938;810.8824220488029;824.7773101262658;895.5790778334181;903.336048004716;30741.538876916864;43444.348334775;43891.78054109733;43983;43997;43376.566234337646;43661.12677746451;43669.126617467635;45537.544624553746;45563.77218113905;8106.189381061906;9868.309218354718;10055.798884022315;10061.949690251538;10063.849042264372;8191.1808819117905;8237.217464340878;8238.134995825441;8246.09292977772;8260.83478330433;12016.21894576854;12160.93919530401;12365.629031129036;12468.875311246862;12476.750464990695;12095.334756588383;12136.271823690608;12153.696157596043;12165.51337946481;12223.816642750366;11810.940945295219;11880.346580938045;11882.227655202385;11890.762184756342;11912.761744765143;34231;42886.35670464946;42917.92705182396;42995;43001.56998430007;3936.153726948691;3986.740861843971;3987.1228329767405;3995.860144894944;4058.9649639342074;29386.853065734747;30014.399712005637;30531;30883;31567.842160789278;3311.6918817067362;3318.900432987014;3339.248669049469;3464.9480257796154;3476.704480119193;356817.21591392136;362968;363736;363951;364070;53254.46745532573;53302.7334863331;53321.2001819973;53496.465035349924;53566.732166338916;45037.87405314893;56220.71889640608;56241;56249;56327.43672563222;15387.307571159101;15706.82198835092;15715.978461399973;15721.528354149394;15742.763858542132;13273.203607783564;13276.668083297995;13281.136726112773;13290;13303.800442993363;11523.827142592867;11715.76568468626;11830.763384732258;12873.678158046123;13224.73550528984;4615.446146462379;5395.244665746799;5509.5041446270025;5543;5557.360903496069;1097.286763603648;1111.6164923101517;1157.9305241685527;1165.918385713009;1213.8604060533153;28144;28168;28285.71714282872;28976;29065.564016539767;1253.3232054690366;1261.4954018392716;1276.5149243287492;1317.6508225320297;1318.3078883586002;14454;14516.346764395628;14720.675139237306;14815.888808339418;14832.555023349101;438144;438465;438677.8066109713;438965;439440;49994.50005499971;50560.49439505632;61002.69498652478;61341;61943;1495.1253516692857;1530.686209327063;1537.3235776258412;1551.6508785523167;1554.7356949318869;30805;32008.879689210768;32020.879269225465;32248.871289504754;32771.18072048218;51113.72215694637;51371.74314128405;51406.48593514017;51500.712482188246;51554.22668660048;397943;557960;567251;567524;587963.0601846963;37765.62234377621;38678.2264354717;38707.8387648371;38741.61258387493;38935.415968759924;48694.67586727735;49223;49815.75092124516;49901.50098498969;50500.49499504958;32981.67018329786;33010.83494582512;33271.00186994394;33466;34527;32444.674517924803;33169.33661326808;33219.66780332166;33721.494177586865;33736.49395259024;38599.842004739905;39784.801075995594;40132.796016119566;40444.58443954447;40450.39324410195;32661.34677306488;33133.83433082819;33422.498662519596;33540.49689254712;34268.14329641779;25710.61434078453;25771.484570308116;25884;26182.607260891484;26336.81484333171 17:40:35 INFO - PROCESS | 1935 | |2;jquery.html;388.74342933663786;412.24970553592476;419.9811008504617;428.80489377333316;452.8731955052584;19575.608487830235;21653.242136525223;22473.325800225997;23016.539669206606;23703.933323000463;19202.519937001547;19470.90264548679;23588.5282294354;24656.50686986259;24664.13675521357;6903.516753827209;7016.193137789169;7291.416686665054;7305;7320.158181809107;7040.2959704029445;7104.36060754532;7138.214796372395;7155.67799449026;7160.820979475503;6933.826654333631;7070.823229419254;7080.079589653338;7157.570545767245;7167.784966451015;7060.6822692978685;7124;7127.643617819088;7143.464240181981;7544.962275188644;7692.499987500794;7784.688612455495;7798;7810.531368117874;7812.726554570563;27109.45781084387;27598;27838.582421263698;27975;28491.287717807012;3394.372041172391;3452.679702834984;3468.7745296555645;3527.770694904849;3572.035550401377;14696.26518674062;15784.763228551581;16405.015699057974;16552;17324;2067.927622533204;2085.958280834375;2104.147820132833;2148.2266384101713;2228.2089858100467;87408.98462006391;105844;141172;144474;170866;19474.902625487066;20525;20829.958502074835;20959.629878367043;20976.47558811042;32757.672423275937;33500.83249583736;33515.329693406;33529.66470335314;33575.664243357256;10721.303115297636;10791.136709063256;10864.945675271572;10991.285566438157;11151.832722509169;7511.32398084175;9081.954590227007;9093.909060909438;9111.726648200565;9241.353105282566;7879.5816752983865;9260.975898386578;9548.474833884204;9642.132208101304;9645.276604254708;2658.9069382571515;2997.970020299769;3951.209758048402;4000.439938408625;4125.658216844366;1002.7894142230067;1016.7254841192837;1026.1585499890018;1033.7674023344687;1067.7757670889046;22121.557568848853;22183.66724499134;22619.547609048055;22683.886580566992;23040.963156657992;1004.6935684616262;1008.4604736465956;1033.9224558158169;1038.200585549123;1038.3977293169944;9636.373635713657;11100;11557.422128893522;11815.586454474224;11923.880761192451;765178;767751;781451;787829;788969;161630;162904;163221.18389407976;167542;168038;1188.8335633011386;1224.7611715715527;1252.3362617812575;1256.830327905721;1258.037601235056;19033.14350854187;19685.21259149618;19975.101120449322;20006.69989950123;20118.497037573594;63254.683726581075;63362.683186583774;64006.67996660173;64689;65522.672386637765;89472.10527894637;91211;92392.07607923835;93870.5919411223;94519.52740236255;10021.949890250502;11363.261388009752;11527.596534121265;11649.650510484698;12043.698907527381;9307.72076837696;10909.618163364245;11029;13180;13744.518941836988;10909.236353455182;11705.356205408785;11740.119491038377;12101.455434505644;12134.939325303318;10744.30162039465;10810.891891080988;10832.5125369357;11180.55277788912;11234.932681395254;11672.533098675958;12011.579594714143;12064.044309144316;12093.75812483763;12104.031677465606;11588.47851846652;11631.069514438655;11719.589814356457;12000.519979201083;12073.396330183632;11575.131865109983;11598.88401115978;11618.302901825917;11959;12049.819252711392 17:40:35 INFO - PROCESS | 1935 | |3;mootools.html;1599.840015998401;1629.706652802496;1651.9752203716941;1659.3030927010655;1662.2104500362323;10962.28745131567;11611.535538578457;11625.767484650301;11639.883601163996;11651.708707282321;11205.775884482326;11291.266067705614;11480.368579728132;11741.647750567476;11762.823557646641;2454.717707463642;2480.268320845346;2481.3176376496517;2489.788367988723;2490.8007359411204;2423.5879900416885;2428.64784606232;2449.461118553915;2453.116877923946;2457.238256140602;2426.720927093389;2440.8535487870695;2442.0476014354417;2474.604063349864;2475.232677869857;2390.497995420964;2396.317049640844;2416.4079800448826;2421.128393778238;2443.0472115874823;2432.4648577312964;2434.586120359543;2444.8899799509063;2450.15469662966;2454.6440766088995;11393.772124557545;11677.065834733201;11683.182177247596;11698.47356868943;11719.589814356541;5671.262735844355;5850.76596936122;5856.5314774818335;5859.941400586024;5867.823965281048;3495.9650403495893;3570.8214589270433;3626.8005259710703;3650.2152037312107;3651.1967367179445;1052.0584077250894;1242.7203879127123;1288.6585054960349;1289.3939848271298;1305.4060402516832;14041.78937315941;14327.856721432756;14723.484678036217;15750.054996700235;15925.442609508611;3646.398344273165;4654.97672511642;4663.183942809995;4692.788824502905;4694.483606803317;11776.234544754563;11788.764224715458;11788.823167652321;11789.469473873696;11809.291442513477;4576.382188404589;4976.62675299354;5122.513361230752;5642.7178641067785;5773.624714393551;4978.65149439543;5020.548150666457;5063.797448102035;5094.2103973884105;5345.705986170798;3859.0931131184147;3888.9027774305305;3962.1679447316387;3979.721419500607;3981;990.5988074829705;1010.1211945607369;1016.6492560066765;1021.4688362051841;1024.7079582319004;916.6929078758691;945.2910317262053;947.9620815167455;957.1624828275259;1009.0766948242423;6634.170728658918;6638;6653.334666533308;6895.620740859201;6911.5853048817235;872.6029656506274;995.2734503812258;1010.4947526236881;1034.9689509314733;1037.3309215556076;4161.188568229166;4335.393044973706;4414.271645178574;4459.777011149429;4493.640508759291;13928.930355348159;14185;14591.41634334636;14621.853781462049;14628.414863405345;14368.353424095732;14620.268986550629;14653.633659158393;14675.77986330206;14686;8893.73318800437;10541.367517948947;10661.360318380766;10808.621698240524;10809.67570972872;6625.602463852184;6651.968944813561;6720.32796720324;6779;6807.0810440589885;12714.11001229905;12735;13007.414666339848;13030.022748293726;13044.869551304366;13036.957043436692;13058.21650700961;13117.803232951323;13246.668833279246;13318.933405332911;8866.335024873031;8867.467951922905;8876.556172191495;8960.775980600538;8963.731088067369;10452.111570516592;10678.946605266923;10684.946575267073;10686.198535109743;10700.30448020876;8950.597223125105;9152.405093668891;9199.264058875407;9210.677626283048;9263.86104208424;8969.910300897169;8981;9062.410943288665;9068.365214434927;9096.454212747256;8005.279524842792;8061.234182752629;8178;8185.754427367188;8211.260986511243;6042.305134909541;6061.787837425669;6083.908741368971;6096.664683442275;6203.348648391854;4537.636989040803;5222.850972785981;5435.918461223164;5708.257926469699;5817.389174136655 17:40:35 INFO - PROCESS | 1935 | |4;prototype.html;383.84262452394523;384.29673697134245;384.9037740564859;389.4586524730625;395.9386295124255;4438.711483753558;5060.886604946911;5097.872553186172;5177;5273.367195936484;5021.497850214986;5058.342415485981;5061.215511595699;5088.160453525164;5117.053345131153;3389.4915762635555;3803.904902377442;4116.6706663467;4324.697271191002;4465.642748580106;3910;4400.987772812239;4418.64650827933;4446.577095329496;4498.143678763976;4265.445492086024;4427.2916333386665;4458.4426946631675;4467.441569803775;4485.1254005468945;3570.696490798259;3631.509746184258;4286.935695964563;4348.4999225089205;4377.562243775629;3541.5395998520025;3868.903277418059;4209.515905670856;4225.9577404226175;4269.402283680287;5001.249812528114;5017.774200160965;5035.874103147414;5053.242013697938;5057.898842023139;4024.3359845625437;4792.520747925179;5187.351581052369;5188.351456067991;5206.349206349207;5458.099413596752;5480;5496.80761173361;5510.476504732044;5515.255440515519;4784.5933095686905;4785.3300537924715;4805.975970120127;4838.95161048392;4864.902701945976;4002.639762421367;4963.029576338987;5475.890482190373;5584.720763961785;5796.50729687977;4883.145449546386;4889.462159162489;4896.877578060506;4907.141250281258;4923.931948581604;4171.185534292532;4457.286834106592;4809.158397280462;5016.438328786529;5037.622178336639;3948.980255098764;4710.105080034782;4730.905381892412;4806.927896081562;5186.740662966837;4383.758893260838;4997.575206107449;5326.707031113326;5451.918221226685;5452.672839629635;1501.4144483651385;1518.627936155649;1704.531253905186;1736.5658585353663;1740.6605711886314;1121.7685755627567;1212.9447380778602;1255.0609308618025;1380.2960490149894;1447.5223176351778;4645.210314246586;4851.28876409547;5147.819826306061;5201.947980520146;5203.843884683465;5127.717975511308;5552.194931734876;5599.552035837123;5647.265855438849;5669.461401166883;4882.487338829443;4916.926246106311;4967.177993813139;4975.9053008338105;5084.745762711849;2337.953240935172;2658.441727237263;2921.4595299869593;2928.194746444702;2933.5918758995804;4775.737334446639;4887.455332514955;5205.55752761012;5348;5580.665160090408;4763.904302010557;4964;5325.307709997753;5354;5455.399906010336;4850.417949846112;4961.156603377434;5141.100307446257;5241.371035475768;5785.768569257182;4327.826886924488;4871.95128048715;5228.457605006608;5238.319018527567;5345.38528069277;4365.4979677337515;4778;5517.2551705519245;5532.75102620375;5618.93240284361;5637.746301416364;5648.378678345461;5683.60214784961;5757.9424205757405;5865.35769984405;3715.442683597482;4171.749695018309;4267.698352002545;4525.456945166601;4542.409486766831;5100.158473851921;5164.70170774017;5578.796375431601;5608.523275521463;5665.045559282041;4832.275158726219;4949.57928576075;5425.864353391197;5472.644278121909;5489.725513724377;4813.4945830688675;5465.426130256265;5473.452654734654;5476.931998260301;5480.424555421623;4932.4327702314695;4948.981121515801;5308.601854860824;5368.932960810038;5404.189821292985;4928.067799659197;5243.790248390022;5450.454954504517;5462.16598310506;5480.91778623313;4973.2540118981;5026.59787217029;5138.922916156186;5360.517553420211;5394.514493695587 17:40:35 INFO - PROCESS | 1935 | |5;yui.html;758.2114600815153;778.2878666020584;796.1202870827735;891.3136884598858;905.533036480901;15634.218289085557;16726.749098763514;17174.22715977781;17936;17984.55038624035;15850.52448426549;16180.676386472263;16312.265948032338;16450.588735281595;16464.259108340124;990.5096976996379;993.0516356879164;999.1107913956585;999.7250756042074;1000;986.378581493658;995.6067353395441;1023.7082431507022;1028;1031.2162756305224;1047.33494231163;1051.5793682526973;1053.7523681934777;1055.0293729768593;1055.37205362809;1050.3907733514543;1050.6007717067466;1050.8528805967171;1051.5373235776271;1052.3790963331671;783.1072577261926;783.1502819440914;783.9686412543491;784.3803395317692;785.6071964017991;17527.824721752873;17560.91219543894;17622.295108195656;18438.63122737551;18689.252429902786;14738.336774845158;14824.407023719039;14972;15077.924610376987;15305.540833775005;12074.517019319217;12123.33321667308;12259.957903578206;12547.309897955609;12862.871371286261;3778.7165962552644;3822.6559609634987;4013;4077.103037331782;4108.198901214264;113126;120693;122796;124547;124809;10733.409662468639;11379.260348077349;11382.658520244406;11725.179237453296;11916.582919597773;22440.438989025406;22562.774372256397;23260.767392326197;23280.65079023816;23308;8028.919710802934;8095.392845536609;8123.268905798506;8201.425900187049;8380;4494.123645889085;4527.411436513232;4563.9087218255445;4589.58693717567;4625.676202665848;3539.752217344761;3627.347077526018;3632.2917031179195;3644.180059486647;3647.6534729200685;626.4362074133317;632.8797528469576;634.5431289471666;655.52474456019;664.7473959895209;673.4814193071307;673.8241768114574;679.3815148720057;694.6144889586328;708.7944496096178;8905.228350075171;9868.361869921126;10289.277327945338;10432.217583681255;10681.092107170822;620.9844753881189;662.7448432353578;683.135833171039;692.9064576282238;712.756255204413;4725.976370118127;5187.8846048099085;5201.45384734605;5364.7585858635675;5375.435579264121;84847.15152848516;93464;97573;98060.52909206368;106978.46510767553;79419.20580794118;81567;81646;82858.342833143;84364.57817710994;66413;66490;72381.2761872385;75981;76729.61635191788;11189.944050279697;11337.319760814378;11386.202965792316;11617;11659.18385712992;46791.766041169576;48376;49457.25814112719;51701.741491292305;52187;55565.221738912085;58233.70883145557;63416.68291658512;64347.034794479056;64834.675826620565;13095.869041309465;13208.669783255495;13486.86513134856;13758.93120534391;14031.438742450187;15187.012844165094;15388.615284617974;15731.921340393224;16191.919040404722;16306.021638701715;14004.579862604138;14338.49815256461;14534.491292804702;15092.54722358331;15148.242587870784;14041.789373159207;14470.638234044232;14622.926885365505;14623.415063397344;14626.634334141732;14050.297485125498;14092.78860817068;14305;14313.499027533986;14348.784768228275;10455.320404173704;10599.682009539727;10642.787144257227;11024.779504409706;11269.267497612957;9299.860502092339;9675.984021677905;11431.485583148611;11631.592894248999;11663.766724665289 17:40:35 INFO - PROCESS | 1935 | __end_tp_report 17:40:35 INFO - PROCESS | 1935 | __start_cc_report 17:40:35 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,1309 17:40:35 INFO - PROCESS | 1935 | __end_cc_report 17:40:35 INFO - PROCESS | 1935 | __startTimestamp1494981635262__endTimestamp 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | ------- Summary: start ------- 17:40:35 INFO - PROCESS | 1935 | Number of tests: 6 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | [#0] dojo.html Cycles:165 Average:6061.07 Median:6281.91 stddev:683.37 (10.9%) stddev-sans-first:646.65 17:40:35 INFO - PROCESS | 1935 | Values: 3167.0 3341.8 3441.6 3481.1 3536.3 6146.8 6148.4 6151.3 6169.3 6239.3 6160.5 6182.0 6185.0 6201.1 6224.8 5449.6 5458.6 5482.0 5516.3 5581.7 5357.5 5358.5 5538.2 5589.2 5615.0 5395.9 5434.6 5455.3 5458.7 5493.3 5525.8 5544.8 5556.3 5559.2 5563.8 5388.4 5492.6 5498.8 5514.1 5526.0 5898.9 5951.3 5984.3 6128.6 6187.5 6393.3 6413.6 6434.3 6440.0 6453.3 6472.6 6540.3 6550.7 6556.1 6558.2 6047.2 6199.7 6214.4 6273.1 6274.9 6596.0 6607.4 6615.6 6685.3 6761.1 6420.9 6424.8 6435.3 6440.8 6447.3 6454.1 6568.5 6572.9 6573.6 6595.0 6271.0 6318.0 6322.5 6499.6 6500.2 6334.0 6365.9 6419.9 6465.4 6503.8 5218.5 5298.9 5302.5 5302.9 5316.6 4675.9 4680.8 4689.6 4700.4 4709.3 6029.4 6086.5 6091.2 6174.6 6270.1 6574.3 6582.0 6585.7 6588.9 6594.6 6223.8 6257.2 6261.1 6265.0 6281.9 5614.6 5658.9 5754.8 5839.2 5839.6 6583.6 6731.5 6781.7 6802.7 6806.3 6587.3 6611.1 6614.4 6624.5 6631.5 6673.1 6780.4 6787.5 6809.2 6811.1 6342.7 6399.2 6421.8 6424.5 6471.7 5547.8 6507.5 6529.8 6561.2 6574.9 6168.0 6268.2 6302.1 6339.6 6373.7 6445.7 6455.0 6462.7 6465.2 6471.8 6147.6 6191.0 6248.3 6308.8 6335.5 5295.8 6412.3 6414.2 6418.2 6426.8 6357.3 6381.8 6394.5 6400.0 6404.7 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | [#1] ext.html Cycles:180 Average:59777.88 Median:34249.57 stddev:120271.48 (351.2%) stddev-sans-first:120527.29 17:40:35 INFO - PROCESS | 1935 | Values: 774.0 810.9 824.8 895.6 903.3 30741.5 43444.3 43891.8 43983.0 43997.0 43376.6 43661.1 43669.1 45537.5 45563.8 8106.2 9868.3 10055.8 10061.9 10063.8 8191.2 8237.2 8238.1 8246.1 8260.8 12016.2 12160.9 12365.6 12468.9 12476.8 12095.3 12136.3 12153.7 12165.5 12223.8 11810.9 11880.3 11882.2 11890.8 11912.8 34231.0 42886.4 42917.9 42995.0 43001.6 3936.2 3986.7 3987.1 3995.9 4059.0 29386.9 30014.4 30531.0 30883.0 31567.8 3311.7 3318.9 3339.2 3464.9 3476.7 356817.2 362968.0 363736.0 363951.0 364070.0 53254.5 53302.7 53321.2 53496.5 53566.7 45037.9 56220.7 56241.0 56249.0 56327.4 15387.3 15706.8 15716.0 15721.5 15742.8 13273.2 13276.7 13281.1 13290.0 13303.8 11523.8 11715.8 11830.8 12873.7 13224.7 4615.4 5395.2 5509.5 5543.0 5557.4 1097.3 1111.6 1157.9 1165.9 1213.9 28144.0 28168.0 28285.7 28976.0 29065.6 1253.3 1261.5 1276.5 1317.7 1318.3 14454.0 14516.3 14720.7 14815.9 14832.6 438144.0 438465.0 438677.8 438965.0 439440.0 49994.5 50560.5 61002.7 61341.0 61943.0 1495.1 1530.7 1537.3 1551.7 1554.7 30805.0 32008.9 32020.9 32248.9 32771.2 51113.7 51371.7 51406.5 51500.7 51554.2 397943.0 557960.0 567251.0 567524.0 587963.1 37765.6 38678.2 38707.8 38741.6 38935.4 48694.7 49223.0 49815.8 49901.5 50500.5 32981.7 33010.8 33271.0 33466.0 34527.0 32444.7 33169.3 33219.7 33721.5 33736.5 38599.8 39784.8 40132.8 40444.6 40450.4 32661.3 33133.8 33422.5 33540.5 34268.1 25710.6 25771.5 25884.0 26182.6 26336.8 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | [#2] jquery.html Cycles:180 Average:44011.43 Median:22152.61 stddev:129423.27 (584.2%) stddev-sans-first:129744.89 17:40:35 INFO - PROCESS | 1935 | Values: 388.7 412.2 420.0 428.8 452.9 19575.6 21653.2 22473.3 23016.5 23703.9 19202.5 19470.9 23588.5 24656.5 24664.1 6903.5 7016.2 7291.4 7305.0 7320.2 7040.3 7104.4 7138.2 7155.7 7160.8 6933.8 7070.8 7080.1 7157.6 7167.8 7060.7 7124.0 7127.6 7143.5 7545.0 7692.5 7784.7 7798.0 7810.5 7812.7 27109.5 27598.0 27838.6 27975.0 28491.3 3394.4 3452.7 3468.8 3527.8 3572.0 14696.3 15784.8 16405.0 16552.0 17324.0 2067.9 2086.0 2104.1 2148.2 2228.2 87409.0 105844.0 141172.0 144474.0 170866.0 19474.9 20525.0 20830.0 20959.6 20976.5 32757.7 33500.8 33515.3 33529.7 33575.7 10721.3 10791.1 10864.9 10991.3 11151.8 7511.3 9082.0 9093.9 9111.7 9241.4 7879.6 9261.0 9548.5 9642.1 9645.3 2658.9 2998.0 3951.2 4000.4 4125.7 1002.8 1016.7 1026.2 1033.8 1067.8 22121.6 22183.7 22619.5 22683.9 23041.0 1004.7 1008.5 1033.9 1038.2 1038.4 9636.4 11100.0 11557.4 11815.6 11923.9 765178.0 767751.0 781451.0 787829.0 788969.0 161630.0 162904.0 163221.2 167542.0 168038.0 1188.8 1224.8 1252.3 1256.8 1258.0 19033.1 19685.2 19975.1 20006.7 20118.5 63254.7 63362.7 64006.7 64689.0 65522.7 89472.1 91211.0 92392.1 93870.6 94519.5 10021.9 11363.3 11527.6 11649.7 12043.7 9307.7 10909.6 11029.0 13180.0 13744.5 10909.2 11705.4 11740.1 12101.5 12134.9 10744.3 10810.9 10832.5 11180.6 11234.9 11672.5 12011.6 12064.0 12093.8 12104.0 11588.5 11631.1 11719.6 12000.5 12073.4 11575.1 11598.9 11618.3 11959.0 12049.8 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | [#3] mootools.html Cycles:180 Average:6892.33 Median:2465.92 stddev:4400.90 (178.5%) stddev-sans-first:4395.28 17:40:35 INFO - PROCESS | 1935 | Values: 1599.8 1629.7 1652.0 1659.3 1662.2 10962.3 11611.5 11625.8 11639.9 11651.7 11205.8 11291.3 11480.4 11741.6 11762.8 2454.7 2480.3 2481.3 2489.8 2490.8 2423.6 2428.6 2449.5 2453.1 2457.2 2426.7 2440.9 2442.0 2474.6 2475.2 2390.5 2396.3 2416.4 2421.1 2443.0 2432.5 2434.6 2444.9 2450.2 2454.6 11393.8 11677.1 11683.2 11698.5 11719.6 5671.3 5850.8 5856.5 5859.9 5867.8 3496.0 3570.8 3626.8 3650.2 3651.2 1052.1 1242.7 1288.7 1289.4 1305.4 14041.8 14327.9 14723.5 15750.1 15925.4 3646.4 4655.0 4663.2 4692.8 4694.5 11776.2 11788.8 11788.8 11789.5 11809.3 4576.4 4976.6 5122.5 5642.7 5773.6 4978.7 5020.5 5063.8 5094.2 5345.7 3859.1 3888.9 3962.2 3979.7 3981.0 990.6 1010.1 1016.6 1021.5 1024.7 916.7 945.3 948.0 957.2 1009.1 6634.2 6638.0 6653.3 6895.6 6911.6 872.6 995.3 1010.5 1035.0 1037.3 4161.2 4335.4 4414.3 4459.8 4493.6 13928.9 14185.0 14591.4 14621.9 14628.4 14368.4 14620.3 14653.6 14675.8 14686.0 8893.7 10541.4 10661.4 10808.6 10809.7 6625.6 6652.0 6720.3 6779.0 6807.1 12714.1 12735.0 13007.4 13030.0 13044.9 13037.0 13058.2 13117.8 13246.7 13318.9 8866.3 8867.5 8876.6 8960.8 8963.7 10452.1 10678.9 10684.9 10686.2 10700.3 8950.6 9152.4 9199.3 9210.7 9263.9 8969.9 8981.0 9062.4 9068.4 9096.5 8005.3 8061.2 8178.0 8185.8 8211.3 6042.3 6061.8 6083.9 6096.7 6203.3 4537.6 5222.9 5435.9 5708.3 5817.4 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | [#4] prototype.html Cycles:180 Average:4572.39 Median:4962.09 stddev:1225.71 (24.7%) stddev-sans-first:1188.15 17:40:35 INFO - PROCESS | 1935 | Values: 383.8 384.3 384.9 389.5 395.9 4438.7 5060.9 5097.9 5177.0 5273.4 5021.5 5058.3 5061.2 5088.2 5117.1 3389.5 3803.9 4116.7 4324.7 4465.6 3910.0 4401.0 4418.6 4446.6 4498.1 4265.4 4427.3 4458.4 4467.4 4485.1 3570.7 3631.5 4286.9 4348.5 4377.6 3541.5 3868.9 4209.5 4226.0 4269.4 5001.2 5017.8 5035.9 5053.2 5057.9 4024.3 4792.5 5187.4 5188.4 5206.3 5458.1 5480.0 5496.8 5510.5 5515.3 4784.6 4785.3 4806.0 4839.0 4864.9 4002.6 4963.0 5475.9 5584.7 5796.5 4883.1 4889.5 4896.9 4907.1 4923.9 4171.2 4457.3 4809.2 5016.4 5037.6 3949.0 4710.1 4730.9 4806.9 5186.7 4383.8 4997.6 5326.7 5451.9 5452.7 1501.4 1518.6 1704.5 1736.6 1740.7 1121.8 1212.9 1255.1 1380.3 1447.5 4645.2 4851.3 5147.8 5201.9 5203.8 5127.7 5552.2 5599.6 5647.3 5669.5 4882.5 4916.9 4967.2 4975.9 5084.7 2338.0 2658.4 2921.5 2928.2 2933.6 4775.7 4887.5 5205.6 5348.0 5580.7 4763.9 4964.0 5325.3 5354.0 5455.4 4850.4 4961.2 5141.1 5241.4 5785.8 4327.8 4872.0 5228.5 5238.3 5345.4 4365.5 4778.0 5517.3 5532.8 5618.9 5637.7 5648.4 5683.6 5757.9 5865.4 3715.4 4171.7 4267.7 4525.5 4542.4 5100.2 5164.7 5578.8 5608.5 5665.0 4832.3 4949.6 5425.9 5472.6 5489.7 4813.5 5465.4 5473.5 5476.9 5480.4 4932.4 4949.0 5308.6 5368.9 5404.2 4928.1 5243.8 5450.5 5462.2 5480.9 4973.3 5026.6 5138.9 5360.5 5394.5 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - PROCESS | 1935 | [#5] yui.html Cycles:180 Average:20661.41 Median:20564.85 stddev:29112.40 (141.6%) stddev-sans-first:29155.71 17:40:35 INFO - PROCESS | 1935 | Values: 758.2 778.3 796.1 891.3 905.5 15634.2 16726.7 17174.2 17936.0 17984.6 15850.5 16180.7 16312.3 16450.6 16464.3 990.5 993.1 999.1 999.7 1000.0 986.4 995.6 1023.7 1028.0 1031.2 1047.3 1051.6 1053.8 1055.0 1055.4 1050.4 1050.6 1050.9 1051.5 1052.4 783.1 783.2 784.0 784.4 785.6 17527.8 17560.9 17622.3 18438.6 18689.3 14738.3 14824.4 14972.0 15077.9 15305.5 12074.5 12123.3 12260.0 12547.3 12862.9 3778.7 3822.7 4013.0 4077.1 4108.2 113126.0 120693.0 122796.0 124547.0 124809.0 10733.4 11379.3 11382.7 11725.2 11916.6 22440.4 22562.8 23260.8 23280.7 23308.0 8028.9 8095.4 8123.3 8201.4 8380.0 4494.1 4527.4 4563.9 4589.6 4625.7 3539.8 3627.3 3632.3 3644.2 3647.7 626.4 632.9 634.5 655.5 664.7 673.5 673.8 679.4 694.6 708.8 8905.2 9868.4 10289.3 10432.2 10681.1 621.0 662.7 683.1 692.9 712.8 4726.0 5187.9 5201.5 5364.8 5375.4 84847.2 93464.0 97573.0 98060.5 106978.5 79419.2 81567.0 81646.0 82858.3 84364.6 66413.0 66490.0 72381.3 75981.0 76729.6 11189.9 11337.3 11386.2 11617.0 11659.2 46791.8 48376.0 49457.3 51701.7 52187.0 55565.2 58233.7 63416.7 64347.0 64834.7 13095.9 13208.7 13486.9 13758.9 14031.4 15187.0 15388.6 15731.9 16191.9 16306.0 14004.6 14338.5 14534.5 15092.5 15148.2 14041.8 14470.6 14622.9 14623.4 14626.6 14050.3 14092.8 14305.0 14313.5 14348.8 10455.3 10599.7 10642.8 11024.8 11269.3 9299.9 9676.0 11431.5 11631.6 11663.8 17:40:35 INFO - PROCESS | 1935 | -------- Summary: end -------- 17:40:35 INFO - PROCESS | 1935 | 17:40:35 INFO - TEST-INFO | 1935: exit 0 17:40:35 INFO - TEST-OK | dromaeo_css | took 1119411ms 17:40:35 INFO - TEST-START | kraken 17:40:35 INFO - operating with platform_type : mac_ 17:40:35 INFO - Initialising browser for kraken test... 17:40:35 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpadUiRX/profile http://localhost:53352/getInfo.html) 17:40:38 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 17:40:38 INFO - PROCESS | 1940 | colorDepth:24 17:40:38 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 17:40:38 INFO - PROCESS | 1940 | __metrics 17:40:38 INFO - PROCESS | 1940 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:40:38 INFO - PROCESS | 1940 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:40:38 INFO - TEST-INFO | 1940: exit 0 17:40:38 INFO - Browser initialized. 17:40:38 INFO - Running cycle 1/1 for kraken test... 17:40:38 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpadUiRX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:50 INFO - PROCESS | 1942 | RSS: Main: 165216256 17:40:50 INFO - PROCESS | 1942 | 17:40:53 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:54 INFO - PROCESS | 1942 | RSS: Main: 215699456 17:40:54 INFO - PROCESS | 1942 | 17:40:57 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-dft) 17:40:57 INFO - PROCESS | 1942 | RSS: Main: 231112704 17:40:57 INFO - PROCESS | 1942 | 17:41:00 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-fft) 17:41:01 INFO - PROCESS | 1942 | RSS: Main: 328364032 17:41:01 INFO - PROCESS | 1942 | 17:41:03 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53352/tests/kraken/driver.html?testName=audio-oscillator) 17:41:04 INFO - PROCESS | 1942 | RSS: Main: 230006784 17:41:04 INFO - PROCESS | 1942 | 17:41:06 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53352/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:41:06 INFO - PROCESS | 1942 | RSS: Main: 231407616 17:41:06 INFO - PROCESS | 1942 | 17:41:09 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53352/tests/kraken/driver.html?testName=imaging-darkroom) 17:41:10 INFO - PROCESS | 1942 | RSS: Main: 271659008 17:41:10 INFO - PROCESS | 1942 | 17:41:13 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53352/tests/kraken/driver.html?testName=imaging-desaturate) 17:41:14 INFO - PROCESS | 1942 | RSS: Main: 272240640 17:41:14 INFO - PROCESS | 1942 | 17:41:17 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53352/tests/kraken/driver.html?testName=json-parse-financial) 17:41:17 INFO - PROCESS | 1942 | RSS: Main: 272646144 17:41:17 INFO - PROCESS | 1942 | 17:41:19 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53352/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:41:19 INFO - PROCESS | 1942 | RSS: Main: 246558720 17:41:19 INFO - PROCESS | 1942 | 17:41:21 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:41:22 INFO - PROCESS | 1942 | RSS: Main: 252588032 17:41:22 INFO - PROCESS | 1942 | 17:41:24 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:41:25 INFO - PROCESS | 1942 | RSS: Main: 257527808 17:41:25 INFO - PROCESS | 1942 | 17:41:28 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:41:29 INFO - PROCESS | 1942 | RSS: Main: 274382848 17:41:29 INFO - PROCESS | 1942 | 17:41:31 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:41:32 INFO - PROCESS | 1942 | RSS: Main: 267128832 17:41:32 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53352/tests/kraken/driver.html?testName=ai-astar) 17:41:34 INFO - PROCESS | 1942 | RSS: Main: 311885824 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | __start_tp_report 17:41:34 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 17:41:34 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 17:41:34 INFO - PROCESS | 1942 | |i|pagename|runs| 17:41:34 INFO - PROCESS | 1942 | |0;ai-astar;105;115;115;114;120;168;165;115;115;115 17:41:34 INFO - PROCESS | 1942 | |1;audio-beat-detection;142;107;116;137;109;109;107;107;127;108 17:41:34 INFO - PROCESS | 1942 | |2;audio-dft;187;186;184;188;183;185;183;184;187;185 17:41:34 INFO - PROCESS | 1942 | |3;audio-fft;85;59;58;94;63;60;59;60;75;58 17:41:34 INFO - PROCESS | 1942 | |4;audio-oscillator;83;70;85;69;70;66;82;68;68;81 17:41:34 INFO - PROCESS | 1942 | |5;imaging-gaussian-blur;89;88;89;88;89;88;88;89;88;97 17:41:34 INFO - PROCESS | 1942 | |6;imaging-darkroom;94;95;94;95;94;109;150;102;95;95 17:41:34 INFO - PROCESS | 1942 | |7;imaging-desaturate;84;84;85;121;130;85;85;84;87;86 17:41:34 INFO - PROCESS | 1942 | |8;json-parse-financial;60;60;60;61;63;66;63;64;64;64 17:41:34 INFO - PROCESS | 1942 | |9;json-stringify-tinderbox;57;62;64;66;61;60;63;84;88;91 17:41:34 INFO - PROCESS | 1942 | |10;stanford-crypto-aes;83;81;83;82;80;83;81;81;82;86 17:41:34 INFO - PROCESS | 1942 | |11;stanford-crypto-ccm;136;134;134;132;134;132;130;150;199;174 17:41:34 INFO - PROCESS | 1942 | |12;stanford-crypto-pbkdf2;161;157;160;161;164;162;157;142;215;218 17:41:34 INFO - PROCESS | 1942 | |13;stanford-crypto-sha256-iterative;65;64;64;62;63;63;64;63;63;63 17:41:34 INFO - PROCESS | 1942 | __end_tp_report 17:41:34 INFO - PROCESS | 1942 | __start_cc_report 17:41:34 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,2101 17:41:34 INFO - PROCESS | 1942 | __end_cc_report 17:41:34 INFO - PROCESS | 1942 | __startTimestamp1494981694493__endTimestamp 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | ------- Summary: start ------- 17:41:34 INFO - PROCESS | 1942 | Number of tests: 14 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#0] ai-astar Cycles:10 Average:124.70 Median:115.00 stddev:22.35 (19.4%) stddev-sans-first:22.54 17:41:34 INFO - PROCESS | 1942 | Values: 105.0 115.0 115.0 114.0 120.0 168.0 165.0 115.0 115.0 115.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:112.50 stddev:13.48 (12.0%) stddev-sans-first:10.81 17:41:34 INFO - PROCESS | 1942 | Values: 142.0 107.0 116.0 137.0 109.0 109.0 107.0 107.0 127.0 108.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.50 stddev:1.75 (0.9%) stddev-sans-first:1.73 17:41:34 INFO - PROCESS | 1942 | Values: 187.0 186.0 184.0 188.0 183.0 185.0 183.0 184.0 187.0 185.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#3] audio-fft Cycles:10 Average:67.10 Median:61.50 stddev:13.00 (21.1%) stddev-sans-first:12.07 17:41:34 INFO - PROCESS | 1942 | Values: 85.0 59.0 58.0 94.0 63.0 60.0 59.0 60.0 75.0 58.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#4] audio-oscillator Cycles:10 Average:74.20 Median:75.50 stddev:7.51 (9.9%) stddev-sans-first:7.26 17:41:34 INFO - PROCESS | 1942 | Values: 83.0 70.0 85.0 69.0 70.0 66.0 82.0 68.0 68.0 81.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:89.00 stddev:2.75 (3.1%) stddev-sans-first:2.92 17:41:34 INFO - PROCESS | 1942 | Values: 89.0 88.0 89.0 88.0 89.0 88.0 88.0 89.0 88.0 97.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:94.50 stddev:17.45 (18.5%) stddev-sans-first:18.25 17:41:34 INFO - PROCESS | 1942 | Values: 94.0 95.0 94.0 95.0 94.0 109.0 150.0 102.0 95.0 95.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:85.00 stddev:17.23 (20.3%) stddev-sans-first:17.96 17:41:34 INFO - PROCESS | 1942 | Values: 84.0 84.0 85.0 121.0 130.0 85.0 85.0 84.0 87.0 86.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.50 stddev:2.12 (3.3%) stddev-sans-first:2.05 17:41:34 INFO - PROCESS | 1942 | Values: 60.0 60.0 60.0 61.0 63.0 66.0 63.0 64.0 64.0 64.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:65.00 stddev:12.80 (19.7%) stddev-sans-first:12.74 17:41:34 INFO - PROCESS | 1942 | Values: 57.0 62.0 64.0 66.0 61.0 60.0 63.0 84.0 88.0 91.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.50 stddev:1.69 (2.0%) stddev-sans-first:1.76 17:41:34 INFO - PROCESS | 1942 | Values: 83.0 81.0 83.0 82.0 80.0 83.0 81.0 81.0 82.0 86.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:135.00 stddev:23.06 (17.1%) stddev-sans-first:24.20 17:41:34 INFO - PROCESS | 1942 | Values: 136.0 134.0 134.0 132.0 134.0 132.0 130.0 150.0 199.0 174.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:161.50 stddev:25.41 (15.7%) stddev-sans-first:26.76 17:41:34 INFO - PROCESS | 1942 | Values: 161.0 157.0 160.0 161.0 164.0 162.0 157.0 142.0 215.0 218.0 17:41:34 INFO - PROCESS | 1942 | 17:41:34 INFO - PROCESS | 1942 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:0.84 (1.3%) stddev-sans-first:0.67 17:41:34 INFO - PROCESS | 1942 | Values: 65.0 64.0 64.0 62.0 63.0 63.0 64.0 63.0 63.0 63.0 17:41:34 INFO - PROCESS | 1942 | -------- Summary: end -------- 17:41:34 INFO - PROCESS | 1942 | 17:41:35 INFO - TEST-INFO | 1942: exit 0 17:41:35 INFO - TEST-OK | kraken | took 59466ms 17:41:35 INFO - SUITE-END | took 1178s 17:41:35 INFO - Completed test suite (00:19:39) 17:41:35 INFO - javascript benchmark 17:41:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7950.254545229666, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3167.0182243504514, 3341.8162001089936, 3441.5698037745265, 3481.129717570608, 3536.2573859489507, 6146.815595532141, 6148.3851614838495, 6151.323354431009, 6169.290531588863, 6239.25128984522, 6160.507159427246, 6182.041783523549, 6185.0, 6201.131841542187, 6224.813255602327, 5449.56403487721, 5458.563314934796, 5482.0, 5516.310461192351, 5581.665100094007, 5357.517823395893, 5358.4641535846495, 5538.169274608801, 5589.245451864007, 5615.0454422748235, 5395.865103372408, 5434.619576629638, 5455.263539422167, 5458.699771512565, 5493.258410114643, 5525.778968841241, 5544.77820887166, 5556.277683901067, 5559.221708960748, 5563.777448902039, 5388.353397592275, 5492.642978206443, 5498.835034948958, 5514.145307477307, 5526.032944234743, 5898.911516327258, 5951.315598706117, 5984.311804142536, 6128.571000029955, 6187.4740647045055, 6393.264774550893, 6413.551051426402, 6434.260060093103, 6439.969604863246, 6453.322401147859, 6472.644004579746, 6540.345965403421, 6550.737970481175, 6556.147700798866, 6558.2458017328145, 6047.244094488189, 6199.721012554446, 6214.440700336946, 6273.0904018917, 6274.901892168852, 6596.043573681789, 6607.405333520006, 6615.63614001225, 6685.264620891698, 6761.087253220752, 6420.935790642127, 6424.839379015562, 6435.324290949477, 6440.838979025468, 6447.2907980122145, 6454.128692626436, 6568.540202185896, 6572.86854262912, 6573.605583664996, 6594.9670251649395, 6270.968645156745, 6318.0, 6322.4942004639515, 6499.642519661369, 6500.154979852589, 6333.968330158412, 6365.872682546323, 6419.871602567922, 6465.353464653497, 6503.804885853432, 5218.478152184751, 5298.947010529922, 5302.549283310885, 5302.867428314323, 5316.574674026068, 4675.929861052087, 4680.836170734008, 4689.577937985598, 4700.412448443944, 4709.293605959134, 6029.397060293935, 6086.543509236825, 6091.238595175603, 6174.598651087665, 6270.059491076375, 6574.309697481791, 6582.0, 6585.703643336062, 6588.901166482507, 6594.604323740591, 6223.782167624111, 6257.155284036597, 6261.092141639437, 6265.0, 6281.905771413432, 5614.578906582023, 5658.91511627326, 5754.827355179351, 5839.182514447981, 5839.562032847553, 6583.572067815672, 6731.495137864582, 6781.7287308509085, 6802.727890884309, 6806.251312355538, 6587.275399705933, 6611.107500487373, 6614.371634694697, 6624.503162262753, 6631.469482441296, 6673.132492775908, 6780.38976492118, 6787.524873258824, 6809.182898052265, 6811.148606424197, 6342.682865856781, 6399.200099987502, 6421.8073457796345, 6424.51816113784, 6471.741130354922, 5547.805826796042, 6507.544471886922, 6529.804105876831, 6561.179852518435, 6574.901376479453, 6168.0439531872635, 6268.216472940882, 6302.117703521419, 6339.5562310637815, 6373.7131829066875, 6445.70994305248, 6454.967725161344, 6462.676866156579, 6465.191851018622, 6471.838204044937, 6147.631142131487, 6191.0, 6248.250209974832, 6308.842278943063, 6335.524835637438, 5295.767364149948, 6412.294647588855, 6414.230292364946, 6418.229812422539, 6426.839329016812, 6357.300696923242, 6381.776637817654, 6394.520410969103, 6400.0, 6404.7438102477245], "value": 6015.434840612505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.9845203095938, 810.8824220488029, 824.7773101262658, 895.5790778334181, 903.336048004716, 30741.538876916864, 43444.348334775, 43891.78054109733, 43983.0, 43997.0, 43376.566234337646, 43661.12677746451, 43669.126617467635, 45537.544624553746, 45563.77218113905, 8106.189381061906, 9868.309218354718, 10055.798884022315, 10061.949690251538, 10063.849042264372, 8191.1808819117905, 8237.217464340878, 8238.134995825441, 8246.09292977772, 8260.83478330433, 12016.21894576854, 12160.93919530401, 12365.629031129036, 12468.875311246862, 12476.750464990695, 12095.334756588383, 12136.271823690608, 12153.696157596043, 12165.51337946481, 12223.816642750366, 11810.940945295219, 11880.346580938045, 11882.227655202385, 11890.762184756342, 11912.761744765143, 34231.0, 42886.35670464946, 42917.92705182396, 42995.0, 43001.56998430007, 3936.153726948691, 3986.740861843971, 3987.1228329767405, 3995.860144894944, 4058.9649639342074, 29386.853065734747, 30014.399712005637, 30531.0, 30883.0, 31567.842160789278, 3311.6918817067362, 3318.900432987014, 3339.248669049469, 3464.9480257796154, 3476.704480119193, 356817.21591392136, 362968.0, 363736.0, 363951.0, 364070.0, 53254.46745532573, 53302.7334863331, 53321.2001819973, 53496.465035349924, 53566.732166338916, 45037.87405314893, 56220.71889640608, 56241.0, 56249.0, 56327.43672563222, 15387.307571159101, 15706.82198835092, 15715.978461399973, 15721.528354149394, 15742.763858542132, 13273.203607783564, 13276.668083297995, 13281.136726112773, 13290.0, 13303.800442993363, 11523.827142592867, 11715.76568468626, 11830.763384732258, 12873.678158046123, 13224.73550528984, 4615.446146462379, 5395.244665746799, 5509.5041446270025, 5543.0, 5557.360903496069, 1097.286763603648, 1111.6164923101517, 1157.9305241685527, 1165.918385713009, 1213.8604060533153, 28144.0, 28168.0, 28285.71714282872, 28976.0, 29065.564016539767, 1253.3232054690366, 1261.4954018392716, 1276.5149243287492, 1317.6508225320297, 1318.3078883586002, 14454.0, 14516.346764395628, 14720.675139237306, 14815.888808339418, 14832.555023349101, 438144.0, 438465.0, 438677.8066109713, 438965.0, 439440.0, 49994.50005499971, 50560.49439505632, 61002.69498652478, 61341.0, 61943.0, 1495.1253516692857, 1530.686209327063, 1537.3235776258412, 1551.6508785523167, 1554.7356949318869, 30805.0, 32008.879689210768, 32020.879269225465, 32248.871289504754, 32771.18072048218, 51113.72215694637, 51371.74314128405, 51406.48593514017, 51500.712482188246, 51554.22668660048, 397943.0, 557960.0, 567251.0, 567524.0, 587963.0601846963, 37765.62234377621, 38678.2264354717, 38707.8387648371, 38741.61258387493, 38935.415968759924, 48694.67586727735, 49223.0, 49815.75092124516, 49901.50098498969, 50500.49499504958, 32981.67018329786, 33010.83494582512, 33271.00186994394, 33466.0, 34527.0, 32444.674517924803, 33169.33661326808, 33219.66780332166, 33721.494177586865, 33736.49395259024, 38599.842004739905, 39784.801075995594, 40132.796016119566, 40444.58443954447, 40450.39324410195, 32661.34677306488, 33133.83433082819, 33422.498662519596, 33540.49689254712, 34268.14329641779, 25710.61434078453, 25771.484570308116, 25884.0, 26182.607260891484, 26336.81484333171], "value": 20086.289799595965, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.74342933663786, 412.24970553592476, 419.9811008504617, 428.80489377333316, 452.8731955052584, 19575.608487830235, 21653.242136525223, 22473.325800225997, 23016.539669206606, 23703.933323000463, 19202.519937001547, 19470.90264548679, 23588.5282294354, 24656.50686986259, 24664.13675521357, 6903.516753827209, 7016.193137789169, 7291.416686665054, 7305.0, 7320.158181809107, 7040.2959704029445, 7104.36060754532, 7138.214796372395, 7155.67799449026, 7160.820979475503, 6933.826654333631, 7070.823229419254, 7080.079589653338, 7157.570545767245, 7167.784966451015, 7060.6822692978685, 7124.0, 7127.643617819088, 7143.464240181981, 7544.962275188644, 7692.499987500794, 7784.688612455495, 7798.0, 7810.531368117874, 7812.726554570563, 27109.45781084387, 27598.0, 27838.582421263698, 27975.0, 28491.287717807012, 3394.372041172391, 3452.679702834984, 3468.7745296555645, 3527.770694904849, 3572.035550401377, 14696.26518674062, 15784.763228551581, 16405.015699057974, 16552.0, 17324.0, 2067.927622533204, 2085.958280834375, 2104.147820132833, 2148.2266384101713, 2228.2089858100467, 87408.98462006391, 105844.0, 141172.0, 144474.0, 170866.0, 19474.902625487066, 20525.0, 20829.958502074835, 20959.629878367043, 20976.47558811042, 32757.672423275937, 33500.83249583736, 33515.329693406, 33529.66470335314, 33575.664243357256, 10721.303115297636, 10791.136709063256, 10864.945675271572, 10991.285566438157, 11151.832722509169, 7511.32398084175, 9081.954590227007, 9093.909060909438, 9111.726648200565, 9241.353105282566, 7879.5816752983865, 9260.975898386578, 9548.474833884204, 9642.132208101304, 9645.276604254708, 2658.9069382571515, 2997.970020299769, 3951.209758048402, 4000.439938408625, 4125.658216844366, 1002.7894142230067, 1016.7254841192837, 1026.1585499890018, 1033.7674023344687, 1067.7757670889046, 22121.557568848853, 22183.66724499134, 22619.547609048055, 22683.886580566992, 23040.963156657992, 1004.6935684616262, 1008.4604736465956, 1033.9224558158169, 1038.200585549123, 1038.3977293169944, 9636.373635713657, 11100.0, 11557.422128893522, 11815.586454474224, 11923.880761192451, 765178.0, 767751.0, 781451.0, 787829.0, 788969.0, 161630.0, 162904.0, 163221.18389407976, 167542.0, 168038.0, 1188.8335633011386, 1224.7611715715527, 1252.3362617812575, 1256.830327905721, 1258.037601235056, 19033.14350854187, 19685.21259149618, 19975.101120449322, 20006.69989950123, 20118.497037573594, 63254.683726581075, 63362.683186583774, 64006.67996660173, 64689.0, 65522.672386637765, 89472.10527894637, 91211.0, 92392.07607923835, 93870.5919411223, 94519.52740236255, 10021.949890250502, 11363.261388009752, 11527.596534121265, 11649.650510484698, 12043.698907527381, 9307.72076837696, 10909.618163364245, 11029.0, 13180.0, 13744.518941836988, 10909.236353455182, 11705.356205408785, 11740.119491038377, 12101.455434505644, 12134.939325303318, 10744.30162039465, 10810.891891080988, 10832.5125369357, 11180.55277788912, 11234.932681395254, 11672.533098675958, 12011.579594714143, 12064.044309144316, 12093.75812483763, 12104.031677465606, 11588.47851846652, 11631.069514438655, 11719.589814356457, 12000.519979201083, 12073.396330183632, 11575.131865109983, 11598.88401115978, 11618.302901825917, 11959.0, 12049.819252711392], "value": 11912.564261634843, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1599.840015998401, 1629.706652802496, 1651.9752203716941, 1659.3030927010655, 1662.2104500362323, 10962.28745131567, 11611.535538578457, 11625.767484650301, 11639.883601163996, 11651.708707282321, 11205.775884482326, 11291.266067705614, 11480.368579728132, 11741.647750567476, 11762.823557646641, 2454.717707463642, 2480.268320845346, 2481.3176376496517, 2489.788367988723, 2490.8007359411204, 2423.5879900416885, 2428.64784606232, 2449.461118553915, 2453.116877923946, 2457.238256140602, 2426.720927093389, 2440.8535487870695, 2442.0476014354417, 2474.604063349864, 2475.232677869857, 2390.497995420964, 2396.317049640844, 2416.4079800448826, 2421.128393778238, 2443.0472115874823, 2432.4648577312964, 2434.586120359543, 2444.8899799509063, 2450.15469662966, 2454.6440766088995, 11393.772124557545, 11677.065834733201, 11683.182177247596, 11698.47356868943, 11719.589814356541, 5671.262735844355, 5850.76596936122, 5856.5314774818335, 5859.941400586024, 5867.823965281048, 3495.9650403495893, 3570.8214589270433, 3626.8005259710703, 3650.2152037312107, 3651.1967367179445, 1052.0584077250894, 1242.7203879127123, 1288.6585054960349, 1289.3939848271298, 1305.4060402516832, 14041.78937315941, 14327.856721432756, 14723.484678036217, 15750.054996700235, 15925.442609508611, 3646.398344273165, 4654.97672511642, 4663.183942809995, 4692.788824502905, 4694.483606803317, 11776.234544754563, 11788.764224715458, 11788.823167652321, 11789.469473873696, 11809.291442513477, 4576.382188404589, 4976.62675299354, 5122.513361230752, 5642.7178641067785, 5773.624714393551, 4978.65149439543, 5020.548150666457, 5063.797448102035, 5094.2103973884105, 5345.705986170798, 3859.0931131184147, 3888.9027774305305, 3962.1679447316387, 3979.721419500607, 3981.0, 990.5988074829705, 1010.1211945607369, 1016.6492560066765, 1021.4688362051841, 1024.7079582319004, 916.6929078758691, 945.2910317262053, 947.9620815167455, 957.1624828275259, 1009.0766948242423, 6634.170728658918, 6638.0, 6653.334666533308, 6895.620740859201, 6911.5853048817235, 872.6029656506274, 995.2734503812258, 1010.4947526236881, 1034.9689509314733, 1037.3309215556076, 4161.188568229166, 4335.393044973706, 4414.271645178574, 4459.777011149429, 4493.640508759291, 13928.930355348159, 14185.0, 14591.41634334636, 14621.853781462049, 14628.414863405345, 14368.353424095732, 14620.268986550629, 14653.633659158393, 14675.77986330206, 14686.0, 8893.73318800437, 10541.367517948947, 10661.360318380766, 10808.621698240524, 10809.67570972872, 6625.602463852184, 6651.968944813561, 6720.32796720324, 6779.0, 6807.0810440589885, 12714.11001229905, 12735.0, 13007.414666339848, 13030.022748293726, 13044.869551304366, 13036.957043436692, 13058.21650700961, 13117.803232951323, 13246.668833279246, 13318.933405332911, 8866.335024873031, 8867.467951922905, 8876.556172191495, 8960.775980600538, 8963.731088067369, 10452.111570516592, 10678.946605266923, 10684.946575267073, 10686.198535109743, 10700.30448020876, 8950.597223125105, 9152.405093668891, 9199.264058875407, 9210.677626283048, 9263.86104208424, 8969.910300897169, 8981.0, 9062.410943288665, 9068.365214434927, 9096.454212747256, 8005.279524842792, 8061.234182752629, 8178.0, 8185.754427367188, 8211.260986511243, 6042.305134909541, 6061.787837425669, 6083.908741368971, 6096.664683442275, 6203.348648391854, 4537.636989040803, 5222.850972785981, 5435.918461223164, 5708.257926469699, 5817.389174136655], "value": 5220.636552473521, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.84262452394523, 384.29673697134245, 384.9037740564859, 389.4586524730625, 395.9386295124255, 4438.711483753558, 5060.886604946911, 5097.872553186172, 5177.0, 5273.367195936484, 5021.497850214986, 5058.342415485981, 5061.215511595699, 5088.160453525164, 5117.053345131153, 3389.4915762635555, 3803.904902377442, 4116.6706663467, 4324.697271191002, 4465.642748580106, 3910.0, 4400.987772812239, 4418.64650827933, 4446.577095329496, 4498.143678763976, 4265.445492086024, 4427.2916333386665, 4458.4426946631675, 4467.441569803775, 4485.1254005468945, 3570.696490798259, 3631.509746184258, 4286.935695964563, 4348.4999225089205, 4377.562243775629, 3541.5395998520025, 3868.903277418059, 4209.515905670856, 4225.9577404226175, 4269.402283680287, 5001.249812528114, 5017.774200160965, 5035.874103147414, 5053.242013697938, 5057.898842023139, 4024.3359845625437, 4792.520747925179, 5187.351581052369, 5188.351456067991, 5206.349206349207, 5458.099413596752, 5480.0, 5496.80761173361, 5510.476504732044, 5515.255440515519, 4784.5933095686905, 4785.3300537924715, 4805.975970120127, 4838.95161048392, 4864.902701945976, 4002.639762421367, 4963.029576338987, 5475.890482190373, 5584.720763961785, 5796.50729687977, 4883.145449546386, 4889.462159162489, 4896.877578060506, 4907.141250281258, 4923.931948581604, 4171.185534292532, 4457.286834106592, 4809.158397280462, 5016.438328786529, 5037.622178336639, 3948.980255098764, 4710.105080034782, 4730.905381892412, 4806.927896081562, 5186.740662966837, 4383.758893260838, 4997.575206107449, 5326.707031113326, 5451.918221226685, 5452.672839629635, 1501.4144483651385, 1518.627936155649, 1704.531253905186, 1736.5658585353663, 1740.6605711886314, 1121.7685755627567, 1212.9447380778602, 1255.0609308618025, 1380.2960490149894, 1447.5223176351778, 4645.210314246586, 4851.28876409547, 5147.819826306061, 5201.947980520146, 5203.843884683465, 5127.717975511308, 5552.194931734876, 5599.552035837123, 5647.265855438849, 5669.461401166883, 4882.487338829443, 4916.926246106311, 4967.177993813139, 4975.9053008338105, 5084.745762711849, 2337.953240935172, 2658.441727237263, 2921.4595299869593, 2928.194746444702, 2933.5918758995804, 4775.737334446639, 4887.455332514955, 5205.55752761012, 5348.0, 5580.665160090408, 4763.904302010557, 4964.0, 5325.307709997753, 5354.0, 5455.399906010336, 4850.417949846112, 4961.156603377434, 5141.100307446257, 5241.371035475768, 5785.768569257182, 4327.826886924488, 4871.95128048715, 5228.457605006608, 5238.319018527567, 5345.38528069277, 4365.4979677337515, 4778.0, 5517.2551705519245, 5532.75102620375, 5618.93240284361, 5637.746301416364, 5648.378678345461, 5683.60214784961, 5757.9424205757405, 5865.35769984405, 3715.442683597482, 4171.749695018309, 4267.698352002545, 4525.456945166601, 4542.409486766831, 5100.158473851921, 5164.70170774017, 5578.796375431601, 5608.523275521463, 5665.045559282041, 4832.275158726219, 4949.57928576075, 5425.864353391197, 5472.644278121909, 5489.725513724377, 4813.4945830688675, 5465.426130256265, 5473.452654734654, 5476.931998260301, 5480.424555421623, 4932.4327702314695, 4948.981121515801, 5308.601854860824, 5368.932960810038, 5404.189821292985, 4928.067799659197, 5243.790248390022, 5450.454954504517, 5462.16598310506, 5480.91778623313, 4973.2540118981, 5026.59787217029, 5138.922916156186, 5360.517553420211, 5394.514493695587], "value": 4226.598173400407, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.2114600815153, 778.2878666020584, 796.1202870827735, 891.3136884598858, 905.533036480901, 15634.218289085557, 16726.749098763514, 17174.22715977781, 17936.0, 17984.55038624035, 15850.52448426549, 16180.676386472263, 16312.265948032338, 16450.588735281595, 16464.259108340124, 990.5096976996379, 993.0516356879164, 999.1107913956585, 999.7250756042074, 1000.0, 986.378581493658, 995.6067353395441, 1023.7082431507022, 1028.0, 1031.2162756305224, 1047.33494231163, 1051.5793682526973, 1053.7523681934777, 1055.0293729768593, 1055.37205362809, 1050.3907733514543, 1050.6007717067466, 1050.8528805967171, 1051.5373235776271, 1052.3790963331671, 783.1072577261926, 783.1502819440914, 783.9686412543491, 784.3803395317692, 785.6071964017991, 17527.824721752873, 17560.91219543894, 17622.295108195656, 18438.63122737551, 18689.252429902786, 14738.336774845158, 14824.407023719039, 14972.0, 15077.924610376987, 15305.540833775005, 12074.517019319217, 12123.33321667308, 12259.957903578206, 12547.309897955609, 12862.871371286261, 3778.7165962552644, 3822.6559609634987, 4013.0, 4077.103037331782, 4108.198901214264, 113126.0, 120693.0, 122796.0, 124547.0, 124809.0, 10733.409662468639, 11379.260348077349, 11382.658520244406, 11725.179237453296, 11916.582919597773, 22440.438989025406, 22562.774372256397, 23260.767392326197, 23280.65079023816, 23308.0, 8028.919710802934, 8095.392845536609, 8123.268905798506, 8201.425900187049, 8380.0, 4494.123645889085, 4527.411436513232, 4563.9087218255445, 4589.58693717567, 4625.676202665848, 3539.752217344761, 3627.347077526018, 3632.2917031179195, 3644.180059486647, 3647.6534729200685, 626.4362074133317, 632.8797528469576, 634.5431289471666, 655.52474456019, 664.7473959895209, 673.4814193071307, 673.8241768114574, 679.3815148720057, 694.6144889586328, 708.7944496096178, 8905.228350075171, 9868.361869921126, 10289.277327945338, 10432.217583681255, 10681.092107170822, 620.9844753881189, 662.7448432353578, 683.135833171039, 692.9064576282238, 712.756255204413, 4725.976370118127, 5187.8846048099085, 5201.45384734605, 5364.7585858635675, 5375.435579264121, 84847.15152848516, 93464.0, 97573.0, 98060.52909206368, 106978.46510767553, 79419.20580794118, 81567.0, 81646.0, 82858.342833143, 84364.57817710994, 66413.0, 66490.0, 72381.2761872385, 75981.0, 76729.61635191788, 11189.944050279697, 11337.319760814378, 11386.202965792316, 11617.0, 11659.18385712992, 46791.766041169576, 48376.0, 49457.25814112719, 51701.741491292305, 52187.0, 55565.221738912085, 58233.70883145557, 63416.68291658512, 64347.034794479056, 64834.675826620565, 13095.869041309465, 13208.669783255495, 13486.86513134856, 13758.93120534391, 14031.438742450187, 15187.012844165094, 15388.615284617974, 15731.921340393224, 16191.919040404722, 16306.021638701715, 14004.579862604138, 14338.49815256461, 14534.491292804702, 15092.54722358331, 15148.242587870784, 14041.789373159207, 14470.638234044232, 14622.926885365505, 14623.415063397344, 14626.634334141732, 14050.297485125498, 14092.78860817068, 14305.0, 14313.499027533986, 14348.784768228275, 10455.320404173704, 10599.682009539727, 10642.787144257227, 11024.779504409706, 11269.267497612957, 9299.860502092339, 9675.984021677905, 11431.485583148611, 11631.592894248999, 11663.766724665289], "value": 7949.8090823524, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1445.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 114.0, 120.0, 168.0, 165.0, 115.0, 115.0, 115.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 116.0, 137.0, 109.0, 109.0, 107.0, 107.0, 127.0, 108.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 184.0, 188.0, 183.0, 185.0, 183.0, 184.0, 187.0, 185.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 58.0, 94.0, 63.0, 60.0, 59.0, 60.0, 75.0, 58.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 85.0, 69.0, 70.0, 66.0, 82.0, 68.0, 68.0, 81.0], "value": 74.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 88.0, 89.0, 88.0, 88.0, 89.0, 88.0, 97.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 109.0, 150.0, 102.0, 95.0, 95.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 121.0, 130.0, 85.0, 85.0, 84.0, 87.0, 86.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 61.0, 63.0, 66.0, 63.0, 64.0, 64.0, 64.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 64.0, 66.0, 61.0, 60.0, 63.0, 84.0, 88.0, 91.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 83.0, 82.0, 80.0, 83.0, 81.0, 81.0, 82.0, 86.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 134.0, 132.0, 134.0, 132.0, 130.0, 150.0, 199.0, 174.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 157.0, 160.0, 161.0, 164.0, 162.0, 157.0, 142.0, 215.0, 218.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 62.0, 63.0, 63.0, 64.0, 63.0, 63.0, 63.0], "value": 63.4, "unit": "score"}]}]} 17:41:35 INFO - Return code: 0 17:41:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:41:35 INFO - # TBPL SUCCESS # 17:41:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:41:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:41:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:41:35 INFO - 2017-05-16 17:41:35.801 screenresolution[1946:19610] starting screenresolution argv=screenresolution get 17:41:35 INFO - 2017-05-16 17:41:35.804 screenresolution[1946:19610] Display 0: 1600x1200x32@60 17:41:35 INFO - 2017-05-16 17:41:35.810 screenresolution[1947:19613] starting screenresolution argv=screenresolution list 17:41:35 INFO - Available Modes on Display 0 17:41:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:41:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:41:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:41:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:41:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:41:36 INFO - Intel Iris: 17:41:36 INFO - Chipset Model: Intel Iris 17:41:36 INFO - Type: GPU 17:41:36 INFO - Bus: Built-In 17:41:36 INFO - VRAM (Dynamic, Max): 1536 MB 17:41:36 INFO - Vendor: Intel (0x8086) 17:41:36 INFO - Device ID: 0x0a2e 17:41:36 INFO - Revision ID: 0x0009 17:41:36 INFO - Displays: 17:41:36 INFO - Display: 17:41:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:41:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:41:36 INFO - Display Serial Number: 1600x1200 60 17:41:36 INFO - Main Display: Yes 17:41:36 INFO - Mirror: Off 17:41:36 INFO - Online: Yes 17:41:36 INFO - Rotation: Supported 17:41:36 INFO - Return code: 0 17:41:36 INFO - Running post-action listener: _resource_record_post_action 17:41:36 INFO - [mozharness: 2017-05-17 00:41:36.043909Z] Finished run-tests step (success) 17:41:36 INFO - Running post-run listener: _resource_record_post_run 17:41:36 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 835584; Write bytes: 422389760; Read time: 8; Write time: 1416 17:41:36 INFO - TinderboxPrint: CPU usage
53.6% 17:41:36 INFO - TinderboxPrint: I/O read bytes / time
835,584 / 8 17:41:36 INFO - TinderboxPrint: I/O write bytes / time
422,389,760 / 1,416 17:41:36 INFO - TinderboxPrint: CPU idle
2,216.4 (46.4%) 17:41:36 INFO - TinderboxPrint: CPU system
951.4 (19.9%) 17:41:36 INFO - TinderboxPrint: CPU user
1,609.9 (33.7%) 17:41:36 INFO - TinderboxPrint: Swap in / out
122,204,160 / 0 17:41:36 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119325184; Write bytes: 112926720; Read time: 10797; Write time: 219 17:41:36 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 790528; Write bytes: 298395648; Read time: 8; Write time: 1164 17:41:36 INFO - Running post-run listener: _upload_blobber_files 17:41:36 INFO - Blob upload gear active. 17:41:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:41:36 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 17:41:36 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:41:36 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:41:36 INFO - (blobuploader) - INFO - Open directory for files ... 17:41:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:41:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:41:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:38 INFO - (blobuploader) - INFO - Done attempting. 17:41:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:41:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:41:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:39 INFO - (blobuploader) - INFO - Done attempting. 17:41:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:41:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:41:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:40 INFO - (blobuploader) - INFO - Done attempting. 17:41:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:41:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:41:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:42 INFO - (blobuploader) - INFO - Done attempting. 17:41:42 INFO - (blobuploader) - INFO - Iteration through files over. 17:41:42 INFO - Return code: 0 17:41:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:41:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:41:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a10d8503f93a750edf4b3308c3c68bf29d498dde120728dc19631f6d4382b491c12e847c8f607943dc7768171beeada711ca886a9f9683e9747656311f306a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a9a0ff4792f1a9a345ebcb940158e99c1c71ef839ae7e3feb25f15fdc35adc0062a34a9f28b07d84843cfcba5b4e7102adec142ff2cafb991b55e55a38233a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db2a8befb4efdbf8005531214b3f41f443dd174ee9f2caa6342ae19b09974c8e59fe14600ab9808178b45c090bf74e95fd4515773a0be68739ef8d9ecbc0977d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d01f6a259f4d18e3c099787be7fea0c7d257bd3e360f2432e40961f65228e02194a07ce68e9c0f616706fa2901f15d75e63f3bdb634a33a8ae730471b5739a5f"} 17:41:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:41:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:41:42 INFO - Contents: 17:41:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a10d8503f93a750edf4b3308c3c68bf29d498dde120728dc19631f6d4382b491c12e847c8f607943dc7768171beeada711ca886a9f9683e9747656311f306a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a9a0ff4792f1a9a345ebcb940158e99c1c71ef839ae7e3feb25f15fdc35adc0062a34a9f28b07d84843cfcba5b4e7102adec142ff2cafb991b55e55a38233a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db2a8befb4efdbf8005531214b3f41f443dd174ee9f2caa6342ae19b09974c8e59fe14600ab9808178b45c090bf74e95fd4515773a0be68739ef8d9ecbc0977d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d01f6a259f4d18e3c099787be7fea0c7d257bd3e360f2432e40961f65228e02194a07ce68e9c0f616706fa2901f15d75e63f3bdb634a33a8ae730471b5739a5f"} 17:41:42 INFO - Running post-run listener: copy_logs_to_upload_dir 17:41:42 INFO - Copying logs to upload dir... 17:41:42 INFO - mkdir: /builds/slave/test/build/upload/logs 17:41:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.724098 ========= master_lag: 0.95 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-16 17:41:43.547501) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 17:41:43.551574) ========= 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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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/Mozilla-Beta/sha512/46a10d8503f93a750edf4b3308c3c68bf29d498dde120728dc19631f6d4382b491c12e847c8f607943dc7768171beeada711ca886a9f9683e9747656311f306a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a9a0ff4792f1a9a345ebcb940158e99c1c71ef839ae7e3feb25f15fdc35adc0062a34a9f28b07d84843cfcba5b4e7102adec142ff2cafb991b55e55a38233a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db2a8befb4efdbf8005531214b3f41f443dd174ee9f2caa6342ae19b09974c8e59fe14600ab9808178b45c090bf74e95fd4515773a0be68739ef8d9ecbc0977d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d01f6a259f4d18e3c099787be7fea0c7d257bd3e360f2432e40961f65228e02194a07ce68e9c0f616706fa2901f15d75e63f3bdb634a33a8ae730471b5739a5f"} build_url:https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/Dxmw0KSWSBiiSbt228Q2FA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46a10d8503f93a750edf4b3308c3c68bf29d498dde120728dc19631f6d4382b491c12e847c8f607943dc7768171beeada711ca886a9f9683e9747656311f306a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a9a0ff4792f1a9a345ebcb940158e99c1c71ef839ae7e3feb25f15fdc35adc0062a34a9f28b07d84843cfcba5b4e7102adec142ff2cafb991b55e55a38233a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db2a8befb4efdbf8005531214b3f41f443dd174ee9f2caa6342ae19b09974c8e59fe14600ab9808178b45c090bf74e95fd4515773a0be68739ef8d9ecbc0977d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d01f6a259f4d18e3c099787be7fea0c7d257bd3e360f2432e40961f65228e02194a07ce68e9c0f616706fa2901f15d75e63f3bdb634a33a8ae730471b5739a5f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 17:41:43.590448) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 17:41:43.590788) ========= 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.JwyEuF3zIx/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.SP8FqJhUz4/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005407 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 17:41:43.640586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 17:41:43.641303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 17:41:43.641771) ========= ========= Total master_lag: 1.11 =========