builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0207 starttime: 1495722657.56 results: success (0) revision: 23a341e9b53d04f80ea6a66ced2d72cdc17afffb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.555344) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.555843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.556133) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.673630) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.674030) ========= 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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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-25 07:30:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-05-25 07:30:57 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133157 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.830465) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.830800) ========= 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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.034496 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 07:30:57.887130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 07:30:57.887489) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 23a341e9b53d04f80ea6a66ced2d72cdc17afffb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 23a341e9b53d04f80ea6a66ced2d72cdc17afffb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wgu5uJzJGX/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.CXlpmJdB43/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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-25 07:30:57,985 truncating revision to first 12 chars 2017-05-25 07:30:57,985 Setting DEBUG logging. 2017-05-25 07:30:57,985 attempt 1/10 2017-05-25 07:30:57,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/23a341e9b53d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 07:30:59,819 unpacking tar archive at: mozilla-central-23a341e9b53d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.111238 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 07:31:00.021476) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 07:31:00.021911) ========= script_repo_revision: 23a341e9b53d04f80ea6a66ced2d72cdc17afffb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 07:31:00.022321) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 07:31:00.022706) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 07:31:00.042107) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-25 07:31:00.042580) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Wgu5uJzJGX/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.CXlpmJdB43/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:31:00 INFO - MultiFileLogger online at 20170525 07:31:00 in /builds/slave/test 07:31:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:31:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:00 INFO - {'append_to_log': False, 07:31:00 INFO - 'base_work_dir': '/builds/slave/test', 07:31:00 INFO - 'blob_upload_branch': 'Firefox', 07:31:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:00 INFO - 'branch': 'Firefox', 07:31:00 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:00 INFO - 'config_files': ('talos/mac_config.py',), 07:31:00 INFO - 'default_actions': ('clobber', 07:31:00 INFO - 'read-buildbot-config', 07:31:00 INFO - 'download-and-extract', 07:31:00 INFO - 'populate-webroot', 07:31:00 INFO - 'create-virtualenv', 07:31:00 INFO - 'install', 07:31:00 INFO - 'run-tests'), 07:31:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:00 INFO - 'download_minidump_stackwalk': True, 07:31:00 INFO - 'download_symbols': 'ondemand', 07:31:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:00 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:00 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:00 INFO - 'gecko_profile': False, 07:31:00 INFO - 'gecko_profile_interval': 0, 07:31:00 INFO - 'installer_path': 'installer.exe', 07:31:00 INFO - 'log_level': 'info', 07:31:00 INFO - 'log_name': 'talos', 07:31:00 INFO - 'log_to_console': True, 07:31:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:31:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:31:00 INFO - 'opt_config_files': (), 07:31:00 INFO - 'pip_index': False, 07:31:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:00 INFO - 'cmd': ('bash', 07:31:00 INFO - '-c', 07:31:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:00 INFO - 'enabled': True, 07:31:00 INFO - 'halt_on_failure': False, 07:31:00 INFO - 'name': 'check_screen_resolution'},), 07:31:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:00 INFO - 'cmd': ('bash', 07:31:00 INFO - '-c', 07:31:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:00 INFO - 'enabled': True, 07:31:00 INFO - 'halt_on_failure': False, 07:31:00 INFO - 'name': 'check_screen_resolution'},), 07:31:00 INFO - 'run_cmd_checks_enabled': True, 07:31:00 INFO - 'suite': 'dromaeojs-e10s', 07:31:00 INFO - 'system_bits': '32', 07:31:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:00 INFO - 'title': 't-yosemite-r7-0207', 07:31:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:00 INFO - 'use_talos_json': True, 07:31:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:00 INFO - 'work_dir': 'build'} 07:31:00 INFO - [mozharness: 2017-05-25 14:31:00.252425Z] Running clobber step. 07:31:00 INFO - Running pre-action listener: _resource_record_pre_action 07:31:00 INFO - Running main action method: clobber 07:31:00 INFO - rmtree: /builds/slave/test/build 07:31:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:02 INFO - Running post-action listener: _resource_record_post_action 07:31:02 INFO - [mozharness: 2017-05-25 14:31:02.874449Z] Finished clobber step (success) 07:31:02 INFO - [mozharness: 2017-05-25 14:31:02.874618Z] Running read-buildbot-config step. 07:31:02 INFO - Running pre-action listener: _resource_record_pre_action 07:31:02 INFO - Running main action method: read_buildbot_config 07:31:02 INFO - Using buildbot properties: 07:31:02 INFO - { 07:31:02 INFO - "project": "", 07:31:02 INFO - "product": "firefox", 07:31:02 INFO - "who": "Callek@gmail.com", 07:31:02 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:31:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:31:02 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:31:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:31:02 INFO - "stage_platform": "macosx64", 07:31:02 INFO - "basedir": "/builds/slave/test", 07:31:02 INFO - "buildnumber": 80, 07:31:02 INFO - "platform": "yosemite_r7", 07:31:02 INFO - "parent_task_id": "MWsVQ6ORSsiRfwW2Dfj5ZQ", 07:31:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:31:02 INFO - "slavebuilddir": "test", 07:31:02 INFO - "taskId": "A852iDYoSmuySySMi58HTQ", 07:31:02 INFO - "branch": "mozilla-central", 07:31:02 INFO - "script_repo_revision": "production", 07:31:02 INFO - "revision": "23a341e9b53d04f80ea6a66ced2d72cdc17afffb", 07:31:02 INFO - "slavename": "t-yosemite-r7-0207", 07:31:02 INFO - "repo_path": "mozilla-central" 07:31:02 INFO - } 07:31:02 INFO - Finding installer, test and symbols from parent task. 07:31:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A852iDYoSmuySySMi58HTQ'}, attempt #1 07:31:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A852iDYoSmuySySMi58HTQ'}, attempt #1 07:31:04 INFO - Task dependencies: IafiNct4Rxa3xC4y5z1qgg MWsVQ6ORSsiRfwW2Dfj5ZQ 07:31:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ'}, attempt #1 07:31:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:04 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/test_packages.json 07:31:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/target.crashreporter-symbols.zip 07:31:04 INFO - Running post-action listener: _resource_record_post_action 07:31:04 INFO - [mozharness: 2017-05-25 14:31:04.694797Z] Finished read-buildbot-config step (success) 07:31:04 INFO - [mozharness: 2017-05-25 14:31:04.694915Z] Running download-and-extract step. 07:31:04 INFO - Running pre-action listener: _resource_record_pre_action 07:31:04 INFO - Running main action method: download_and_extract 07:31:04 INFO - mkdir: /builds/slave/test/build/tests 07:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:04 INFO - trying https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:31:04 INFO - Downloading https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:31:05 INFO - Downloaded 1325 bytes. 07:31:05 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:31:05 INFO - Using the following test package requirements: 07:31:05 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:31:05 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:31:05 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:31:05 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:31:05 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'jsshell-mac.zip'], 07:31:05 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:31:05 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:31:05 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:31:05 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:31:05 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:31:05 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:31:05 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:31:05 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:31:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:31:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:05 INFO - Fetch https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:31:07 INFO - Content-Length response header: 57629587 07:31:07 INFO - Bytes received: 57629587 07:31:24 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:31:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:31:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:24 INFO - Fetch https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:31:27 INFO - Content-Length response header: 57629587 07:31:27 INFO - Bytes received: 57629587 07:31:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:31:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:31:44 INFO - Fetch https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:31:45 INFO - Content-Length response header: 14411859 07:31:45 INFO - Bytes received: 14411859 07:31:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:45 INFO - trying https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:45 INFO - Downloading https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:31:47 INFO - Downloaded 67203410 bytes. 07:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:47 INFO - mkdir: /builds/slave/test/properties 07:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:31:47 INFO - Writing to file /builds/slave/test/properties/build_url 07:31:47 INFO - Contents: 07:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:31:47 INFO - Running post-action listener: _resource_record_post_action 07:31:47 INFO - Running post-action listener: set_extra_try_arguments 07:31:47 INFO - [mozharness: 2017-05-25 14:31:47.741402Z] Finished download-and-extract step (success) 07:31:47 INFO - [mozharness: 2017-05-25 14:31:47.741519Z] Running populate-webroot step. 07:31:47 INFO - Running pre-action listener: _resource_record_pre_action 07:31:47 INFO - Running main action method: populate_webroot 07:31:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:31:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:31:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:31:47 INFO - '--tppagecycles', 07:31:47 INFO - '7'], 07:31:47 INFO - 'tests': ['tsvgm']}}, 07:31:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:31:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:31:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:31:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:31:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:31:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['damp', 'tps']}, 07:31:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'tests': ['damp', 'tps']}, 07:31:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['dromaeo_dom']}, 07:31:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:31:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:31:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:31:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:31:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['a11yr', 07:31:47 INFO - 'ts_paint', 07:31:47 INFO - 'tpaint', 07:31:47 INFO - 'sessionrestore', 07:31:47 INFO - 'sessionrestore_no_auto_restore', 07:31:47 INFO - 'tabpaint']}, 07:31:47 INFO - 'other-e10s': {'tests': ['a11yr', 07:31:47 INFO - 'ts_paint', 07:31:47 INFO - 'tpaint', 07:31:47 INFO - 'sessionrestore', 07:31:47 INFO - 'sessionrestore_no_auto_restore', 07:31:47 INFO - 'tabpaint']}, 07:31:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:31:47 INFO - 'ts_paint', 07:31:47 INFO - 'tpaint', 07:31:47 INFO - 'sessionrestore', 07:31:47 INFO - 'sessionrestore_no_auto_restore', 07:31:47 INFO - 'tabpaint']}, 07:31:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:31:47 INFO - 'ts_paint', 07:31:47 INFO - 'tpaint', 07:31:47 INFO - 'sessionrestore', 07:31:47 INFO - 'sessionrestore_no_auto_restore', 07:31:47 INFO - 'tabpaint']}, 07:31:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['a11yr', 07:31:47 INFO - 'ts_paint', 07:31:47 INFO - 'tpaint', 07:31:47 INFO - 'sessionrestore', 07:31:47 INFO - 'sessionrestore_no_auto_restore', 07:31:47 INFO - 'tabpaint']}, 07:31:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['a11yr', 07:31:47 INFO - 'ts_paint', 07:31:47 INFO - 'tpaint', 07:31:47 INFO - 'sessionrestore', 07:31:47 INFO - 'sessionrestore_no_auto_restore', 07:31:47 INFO - 'tabpaint']}, 07:31:47 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:31:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:31:47 INFO - 'bloom_basic_ref']}, 07:31:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['tsvgx', 07:31:47 INFO - 'tsvgr_opacity', 07:31:47 INFO - 'tart', 07:31:47 INFO - 'tscrollx', 07:31:47 INFO - 'cart', 07:31:47 INFO - 'tsvg_static']}, 07:31:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:31:47 INFO - 'tsvgr_opacity', 07:31:47 INFO - 'tart', 07:31:47 INFO - 'tscrollx', 07:31:47 INFO - 'cart', 07:31:47 INFO - 'tsvg_static']}, 07:31:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'talos_options': ['--disable-e10s'], 07:31:47 INFO - 'tests': ['tp5o']}, 07:31:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:31:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'talos_options': ['--disable-e10s', 07:31:47 INFO - '--xperf_path', 07:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:47 INFO - 'tests': ['tp5n']}, 07:31:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:31:47 INFO - 'talos_options': ['--xperf_path', 07:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:31:47 INFO - 'tests': ['tp5n']}}, 07:31:47 INFO - 'talos.zip': {'path': '', 07:31:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:31:47 INFO - Running post-action listener: _resource_record_post_action 07:31:47 INFO - [mozharness: 2017-05-25 14:31:47.763211Z] Finished populate-webroot step (success) 07:31:47 INFO - [mozharness: 2017-05-25 14:31:47.763354Z] Running create-virtualenv step. 07:31:47 INFO - Running pre-action listener: _resource_record_pre_action 07:31:47 INFO - Running main action method: create_virtualenv 07:31:47 INFO - Creating virtualenv /builds/slave/test/build/venv 07:31:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:31:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:31:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:31:47 INFO - Using real prefix '/tools/python27' 07:31:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:31:48 INFO - Installing distribute.............................................................................................................................................................................................done. 07:31:51 INFO - Installing pip.................done. 07:31:51 INFO - Return code: 0 07:31:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:31:51 INFO - Reading from file tmpfile_stdout 07:31:51 INFO - Output received: 07:31:51 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:31:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:52 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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:52 INFO - 'HOME': '/Users/cltbld', 07:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:52 INFO - 'LOGNAME': 'cltbld', 07:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:52 INFO - 'MOZ_NO_REMOTE': '1', 07:31:52 INFO - 'NO_EM_RESTART': '1', 07:31:52 INFO - 'PAGER': '/bin/cat', 07:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:52 INFO - 'PWD': '/builds/slave/test', 07:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:52 INFO - 'SHELL': '/bin/bash', 07:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:31:52 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:52 INFO - 'USER': 'cltbld', 07:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:52 INFO - 'XPC_FLAGS': '0x0', 07:31:52 INFO - 'XPC_SERVICE_NAME': '0', 07:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:31:52 INFO - Cleaning up... 07:31:52 INFO - Return code: 0 07:31:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:53 INFO - 'HOME': '/Users/cltbld', 07:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:53 INFO - 'LOGNAME': 'cltbld', 07:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:53 INFO - 'MOZ_NO_REMOTE': '1', 07:31:53 INFO - 'NO_EM_RESTART': '1', 07:31:53 INFO - 'PAGER': '/bin/cat', 07:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:53 INFO - 'PWD': '/builds/slave/test', 07:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:53 INFO - 'SHELL': '/bin/bash', 07:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:31:53 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:53 INFO - 'USER': 'cltbld', 07:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:53 INFO - 'XPC_FLAGS': '0x0', 07:31:53 INFO - 'XPC_SERVICE_NAME': '0', 07:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:53 INFO - Downloading/unpacking psutil>=3.1.1 07:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:31:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:55 INFO - Installing collected packages: psutil 07:31:55 INFO - Running setup.py install for psutil 07:31:55 INFO - building 'psutil._psutil_osx' extension 07:31:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:31:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:31:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:31:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:31:56 INFO - building 'psutil._psutil_posix' extension 07:31:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:31:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:31:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:31:56 INFO - ^ 07:31:56 INFO - 1 warning generated. 07:31:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:31:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:31:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:31:56 INFO - Successfully installed psutil 07:31:56 INFO - Cleaning up... 07:31:56 INFO - Return code: 0 07:31:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:31: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')]} 07:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31: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')]} 07:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:56 INFO - 'HOME': '/Users/cltbld', 07:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:56 INFO - 'LOGNAME': 'cltbld', 07:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:56 INFO - 'MOZ_NO_REMOTE': '1', 07:31:56 INFO - 'NO_EM_RESTART': '1', 07:31:56 INFO - 'PAGER': '/bin/cat', 07:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:56 INFO - 'PWD': '/builds/slave/test', 07:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:56 INFO - 'SHELL': '/bin/bash', 07:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:31:56 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:56 INFO - 'USER': 'cltbld', 07:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:56 INFO - 'XPC_FLAGS': '0x0', 07:31:56 INFO - 'XPC_SERVICE_NAME': '0', 07:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:31:58 INFO - Installing collected packages: mozsystemmonitor 07:31:58 INFO - Running setup.py install for mozsystemmonitor 07:31:58 INFO - Successfully installed mozsystemmonitor 07:31:58 INFO - Cleaning up... 07:31:58 INFO - Return code: 0 07:31:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:31:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:31:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:31:58 INFO - 'HOME': '/Users/cltbld', 07:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:31:58 INFO - 'LOGNAME': 'cltbld', 07:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:31:58 INFO - 'MOZ_NO_REMOTE': '1', 07:31:58 INFO - 'NO_EM_RESTART': '1', 07:31:58 INFO - 'PAGER': '/bin/cat', 07:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:31:58 INFO - 'PWD': '/builds/slave/test', 07:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:31:58 INFO - 'SHELL': '/bin/bash', 07:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:31:58 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:31:58 INFO - 'USER': 'cltbld', 07:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:31:58 INFO - 'XPC_FLAGS': '0x0', 07:31:58 INFO - 'XPC_SERVICE_NAME': '0', 07:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:31:58 INFO - Downloading/unpacking jsonschema==2.5.1 07:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:00 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:00 INFO - warning: no files found matching '*.txt' 07:32:00 INFO - no previously-included directories found matching 'build' 07:32:00 INFO - no previously-included directories found matching 'dist' 07:32:00 INFO - no previously-included directories found matching '.git*' 07:32:00 INFO - Installing collected packages: jsonschema, functools32 07:32:00 INFO - Running setup.py install for functools32 07:32:00 INFO - warning: no files found matching '*.txt' 07:32:00 INFO - no previously-included directories found matching 'build' 07:32:00 INFO - no previously-included directories found matching 'dist' 07:32:00 INFO - no previously-included directories found matching '.git*' 07:32:00 INFO - Successfully installed jsonschema functools32 07:32:00 INFO - Cleaning up... 07:32:00 INFO - Return code: 0 07:32:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:32:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:00 INFO - 'HOME': '/Users/cltbld', 07:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:00 INFO - 'LOGNAME': 'cltbld', 07:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:00 INFO - 'MOZ_NO_REMOTE': '1', 07:32:00 INFO - 'NO_EM_RESTART': '1', 07:32:00 INFO - 'PAGER': '/bin/cat', 07:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:00 INFO - 'PWD': '/builds/slave/test', 07:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:00 INFO - 'SHELL': '/bin/bash', 07:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:00 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:00 INFO - 'USER': 'cltbld', 07:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:00 INFO - 'XPC_FLAGS': '0x0', 07:32:00 INFO - 'XPC_SERVICE_NAME': '0', 07:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:01 INFO - Cleaning up... 07:32:01 INFO - Return code: 0 07:32:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:01 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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:01 INFO - 'HOME': '/Users/cltbld', 07:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:01 INFO - 'LOGNAME': 'cltbld', 07:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:01 INFO - 'MOZ_NO_REMOTE': '1', 07:32:01 INFO - 'NO_EM_RESTART': '1', 07:32:01 INFO - 'PAGER': '/bin/cat', 07:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:01 INFO - 'PWD': '/builds/slave/test', 07:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:01 INFO - 'SHELL': '/bin/bash', 07:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:01 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:01 INFO - 'USER': 'cltbld', 07:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:01 INFO - 'XPC_FLAGS': '0x0', 07:32:01 INFO - 'XPC_SERVICE_NAME': '0', 07:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:02 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:03 INFO - Downloading docopt-0.6.1.tar.gz 07:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:03 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:03 INFO - Running setup.py install for blobuploader 07:32:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:03 INFO - Running setup.py install for requests 07:32:04 INFO - Running setup.py install for docopt 07:32:04 INFO - Successfully installed blobuploader requests docopt 07:32:04 INFO - Cleaning up... 07:32:04 INFO - Return code: 0 07:32:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:04 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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:04 INFO - 'HOME': '/Users/cltbld', 07:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:04 INFO - 'LOGNAME': 'cltbld', 07:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:04 INFO - 'MOZ_NO_REMOTE': '1', 07:32:04 INFO - 'NO_EM_RESTART': '1', 07:32:04 INFO - 'PAGER': '/bin/cat', 07:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:04 INFO - 'PWD': '/builds/slave/test', 07:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:04 INFO - 'SHELL': '/bin/bash', 07:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:04 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:04 INFO - 'USER': 'cltbld', 07:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:04 INFO - 'XPC_FLAGS': '0x0', 07:32:04 INFO - 'XPC_SERVICE_NAME': '0', 07:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:04 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-DehEeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ICsFyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-w1pFHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-gb9q3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ouhj2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-t44xK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-TGyMZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-VUH8b0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-lOAsWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-EVnMEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:05 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-fNnctd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-fjDMdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-rB9sPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-8xuKVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-hgpo4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-PeDMWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:06 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-QbxnqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:06 INFO - Running setup.py install for manifestparser 07:32:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:06 INFO - Running setup.py install for mozcrash 07:32:06 INFO - Running setup.py install for mozdebug 07:32:07 INFO - Running setup.py install for mozdevice 07:32:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Running setup.py install for mozfile 07:32:07 INFO - Running setup.py install for mozhttpd 07:32:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Running setup.py install for mozinfo 07:32:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Running setup.py install for mozInstall 07:32:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:07 INFO - Running setup.py install for mozleak 07:32:07 INFO - Running setup.py install for mozlog 07:32:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:08 INFO - Running setup.py install for moznetwork 07:32:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:08 INFO - Running setup.py install for mozprocess 07:32:08 INFO - Running setup.py install for mozprofile 07:32:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:08 INFO - Running setup.py install for mozrunner 07:32:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:08 INFO - Running setup.py install for mozscreenshot 07:32:08 INFO - Running setup.py install for moztest 07:32:09 INFO - Running setup.py install for mozversion 07:32:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:09 INFO - Cleaning up... 07:32:09 INFO - Return code: 0 07:32:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:09 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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:09 INFO - 'HOME': '/Users/cltbld', 07:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:09 INFO - 'LOGNAME': 'cltbld', 07:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:09 INFO - 'MOZ_NO_REMOTE': '1', 07:32:09 INFO - 'NO_EM_RESTART': '1', 07:32:09 INFO - 'PAGER': '/bin/cat', 07:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:09 INFO - 'PWD': '/builds/slave/test', 07:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:09 INFO - 'SHELL': '/bin/bash', 07:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:09 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:09 INFO - 'USER': 'cltbld', 07:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:09 INFO - 'XPC_FLAGS': '0x0', 07:32:09 INFO - 'XPC_SERVICE_NAME': '0', 07:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:09 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-l7uWPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:09 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-VmGJHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:09 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-j16eFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:09 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-8drNIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:09 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-R9Bna7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Q0qo00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-5SmTA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-sSc0dK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-e0agfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-Zw6lbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-iB9o3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-WinEgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-aP5mz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:10 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-qV8tGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:11 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-1KpmHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:11 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-ZkaBkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:32:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:11 INFO - Running setup.py (path:/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/pip-eviyfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:32:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:12 INFO - Downloading blessings-1.6.tar.gz 07:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:32:12 INFO - Installing collected packages: blessings 07:32:12 INFO - Running setup.py install for blessings 07:32:13 INFO - Successfully installed blessings 07:32:13 INFO - Cleaning up... 07:32:13 INFO - Return code: 0 07:32:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:32:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:13 INFO - Reading from file tmpfile_stdout 07:32:13 INFO - Current package versions: 07:32:13 INFO - blessings == 1.6 07:32:13 INFO - blobuploader == 1.2.4 07:32:13 INFO - docopt == 0.6.1 07:32:13 INFO - functools32 == 3.2.3-2 07:32:13 INFO - jsonschema == 2.5.1 07:32:13 INFO - manifestparser == 1.1 07:32:13 INFO - mozInstall == 1.12 07:32:13 INFO - mozcrash == 1.0 07:32:13 INFO - mozdebug == 0.1 07:32:13 INFO - mozdevice == 0.51 07:32:13 INFO - mozfile == 1.2 07:32:13 INFO - mozhttpd == 0.7 07:32:13 INFO - mozinfo == 0.9 07:32:13 INFO - mozleak == 0.1 07:32:13 INFO - mozlog == 3.4 07:32:13 INFO - moznetwork == 0.27 07:32:13 INFO - mozprocess == 0.25 07:32:13 INFO - mozprofile == 0.28 07:32:13 INFO - mozrunner == 6.13 07:32:13 INFO - mozscreenshot == 0.1 07:32:13 INFO - mozsystemmonitor == 0.3 07:32:13 INFO - moztest == 0.8 07:32:13 INFO - mozversion == 1.4 07:32:13 INFO - psutil == 3.1.1 07:32:13 INFO - requests == 1.2.3 07:32:13 INFO - wsgiref == 0.1.2 07:32:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32: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')]} 07:32:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:13 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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:32:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:13 INFO - 'HOME': '/Users/cltbld', 07:32:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:13 INFO - 'LOGNAME': 'cltbld', 07:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:13 INFO - 'MOZ_NO_REMOTE': '1', 07:32:13 INFO - 'NO_EM_RESTART': '1', 07:32:13 INFO - 'PAGER': '/bin/cat', 07:32:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:13 INFO - 'PWD': '/builds/slave/test', 07:32:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:13 INFO - 'SHELL': '/bin/bash', 07:32:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:13 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:13 INFO - 'USER': 'cltbld', 07:32:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:13 INFO - 'XPC_FLAGS': '0x0', 07:32:13 INFO - 'XPC_SERVICE_NAME': '0', 07:32:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:32:13 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:15 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:32:15 INFO - Installing collected packages: marionette-driver 07:32:15 INFO - Running setup.py install for marionette-driver 07:32:15 INFO - Successfully installed marionette-driver 07:32:15 INFO - Cleaning up... 07:32:15 INFO - Return code: 0 07:32:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:15 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 0x10b4b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffde0d9b810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, '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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:15 INFO - 'HOME': '/Users/cltbld', 07:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:15 INFO - 'LOGNAME': 'cltbld', 07:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:15 INFO - 'MOZ_NO_REMOTE': '1', 07:32:15 INFO - 'NO_EM_RESTART': '1', 07:32:15 INFO - 'PAGER': '/bin/cat', 07:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:15 INFO - 'PWD': '/builds/slave/test', 07:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:15 INFO - 'SHELL': '/bin/bash', 07:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:15 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:15 INFO - 'USER': 'cltbld', 07:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:15 INFO - 'XPC_FLAGS': '0x0', 07:32:15 INFO - 'XPC_SERVICE_NAME': '0', 07:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:32:15 INFO - Cleaning up... 07:32:15 INFO - Return code: 0 07:32:15 INFO - Running post-action listener: _resource_record_post_action 07:32:15 INFO - Running post-action listener: _start_resource_monitoring 07:32:15 INFO - Starting resource monitoring. 07:32:15 INFO - [mozharness: 2017-05-25 14:32:15.840983Z] Finished create-virtualenv step (success) 07:32:15 INFO - [mozharness: 2017-05-25 14:32:15.841936Z] Running install step. 07:32:15 INFO - Running pre-action listener: _resource_record_pre_action 07:32:15 INFO - Running main action method: install 07:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:16 INFO - Reading from file tmpfile_stdout 07:32:16 INFO - Detecting whether we're running mozinstall >=1.0... 07:32:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:32:16 INFO - Reading from file tmpfile_stdout 07:32:16 INFO - Output received: 07:32:16 INFO - Usage: mozinstall [options] installer 07:32:16 INFO - Options: 07:32:16 INFO - -h, --help show this help message and exit 07:32:16 INFO - -d DEST, --destination=DEST 07:32:16 INFO - Directory to install application into. [default: 07:32:16 INFO - "/builds/slave/test"] 07:32:16 INFO - --app=APP Application being installed. [default: firefox] 07:32:16 INFO - mkdir: /builds/slave/test/build/application 07:32:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:32:37 INFO - Reading from file tmpfile_stdout 07:32:37 INFO - Output received: 07:32:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:32:37 INFO - Running post-action listener: _resource_record_post_action 07:32:37 INFO - [mozharness: 2017-05-25 14:32:37.500572Z] Finished install step (success) 07:32:37 INFO - [mozharness: 2017-05-25 14:32:37.500747Z] Running run-tests step. 07:32:37 INFO - Running pre-action listener: _resource_record_pre_action 07:32:37 INFO - Running main action method: run_tests 07:32:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:37 INFO - 2017-05-25 07:32:37.515 screenresolution[1626:7476] starting screenresolution argv=screenresolution get 07:32:37 INFO - 2017-05-25 07:32:37.517 screenresolution[1626:7476] Display 0: 1600x1200x32@60 07:32:37 INFO - 2017-05-25 07:32:37.523 screenresolution[1627:7478] starting screenresolution argv=screenresolution list 07:32:37 INFO - Available Modes on Display 0 07:32:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:37 INFO - Intel Iris: 07:32:37 INFO - Chipset Model: Intel Iris 07:32:37 INFO - Type: GPU 07:32:37 INFO - Bus: Built-In 07:32:37 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:37 INFO - Vendor: Intel (0x8086) 07:32:37 INFO - Device ID: 0x0a2e 07:32:37 INFO - Revision ID: 0x0009 07:32:37 INFO - Displays: 07:32:37 INFO - Display: 07:32:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:37 INFO - Display Serial Number: 1600x1200 60 07:32:37 INFO - Main Display: Yes 07:32:37 INFO - Mirror: Off 07:32:37 INFO - Online: Yes 07:32:37 INFO - Rotation: Supported 07:32:37 INFO - Return code: 0 07:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:32:37 INFO - Python 2.7.3 07:32:37 INFO - Return code: 0 07:32:37 INFO - grabbing minidump binary from tooltool 07:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:37 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 0x10b1c7d30>, '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 0x10b17bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1bcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:32:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:32:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:32:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:32:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:32:37 INFO - Return code: 0 07:32:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:32:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:32:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:32:37 INFO - ENV: RUST_BACKTRACE is now 1 07:32:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:32:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0207 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Wgu5uJzJGX/Render', 07:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:37 INFO - 'HOME': '/Users/cltbld', 07:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:37 INFO - 'LOGNAME': 'cltbld', 07:32:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:32:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:37 INFO - 'MOZ_NO_REMOTE': '1', 07:32:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:32:37 INFO - 'NO_EM_RESTART': '1', 07:32:37 INFO - 'PAGER': '/bin/cat', 07:32:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:37 INFO - 'PWD': '/builds/slave/test', 07:32:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:37 INFO - 'RUST_BACKTRACE': '1', 07:32:37 INFO - 'SHELL': '/bin/bash', 07:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CXlpmJdB43/Listeners', 07:32:37 INFO - 'TMPDIR': '/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/', 07:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:37 INFO - 'USER': 'cltbld', 07:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:37 INFO - 'XPC_FLAGS': '0x0', 07:32:37 INFO - 'XPC_SERVICE_NAME': '0', 07:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0207', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:32:38 INFO - mozversion application_buildid: 20170525061217 07:32:38 INFO - mozversion application_changeset: 23a341e9b53d04f80ea6a66ced2d72cdc17afffb 07:32:38 INFO - mozversion application_display_name: Nightly 07:32:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:32:38 INFO - mozversion application_name: Firefox 07:32:38 INFO - mozversion application_remotingname: firefox 07:32:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:32:38 INFO - mozversion application_vendor: Mozilla 07:32:38 INFO - mozversion application_version: 55.0a1 07:32:38 INFO - mozversion platform_buildid: 20170525061217 07:32:38 INFO - mozversion platform_changeset: 23a341e9b53d04f80ea6a66ced2d72cdc17afffb 07:32:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:32:38 INFO - mozversion platform_version: 55.0a1 07:32:38 INFO - using testdate: 1495722758 07:32:38 INFO - actual date: 1495722758 07:32:38 INFO - starting webserver on 'localhost:49230' 07:32:38 INFO - SUITE-START | Running 2 tests 07:32:38 INFO - TEST-START | dromaeo_css 07:32:38 INFO - operating with platform_type : mac_ 07:32:38 INFO - Initialising browser for dromaeo_css test... 07:32:38 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpiAovOX/profile 07:32:38 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 07:33:11 INFO - TEST-INFO | 1637: killed by SIGTERM 07:33:11 INFO - Browser initialized. 07:33:11 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:11 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpiAovOX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:14 INFO - PID 1647 | 2017-05-25 07:33:14.038 plugin-container[1648:8165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 07:33:14 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:14 INFO - PID 1647 | 2017-05-25 07:33:14.039 plugin-container[1648:8165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 07:33:14 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:14 INFO - PID 1647 | 2017-05-25 07:33:14.039 plugin-container[1648:8165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:14 INFO - PID 1647 | 2017-05-25 07:33:14.424 plugin-container[1649:8209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974f, name = 'com.apple.tsm.portname' 07:33:14 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:14 INFO - PID 1647 | 2017-05-25 07:33:14.424 plugin-container[1649:8209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c03, name = 'com.apple.CFPasteboardClient' 07:33:14 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:14 INFO - PID 1647 | 2017-05-25 07:33:14.424 plugin-container[1649:8209] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:23 INFO - PID 1647 | RSS: Main: 246067200 07:33:23 INFO - PID 1647 | 07:36:26 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 07:36:28 INFO - PID 1647 | RSS: Main: 264450048 07:36:28 INFO - PID 1647 | 07:39:30 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 07:39:30 INFO - PID 1647 | RSS: Main: 273973248 07:39:30 INFO - PID 1647 | 07:42:32 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 07:42:33 INFO - PID 1647 | RSS: Main: 273092608 07:42:33 INFO - PID 1647 | 07:45:34 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 07:45:35 INFO - PID 1647 | RSS: Main: 277217280 07:45:35 INFO - PID 1647 | 07:48:38 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 07:48:38 INFO - PID 1647 | RSS: Main: 277401600 07:48:38 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 07:51:40 INFO - PID 1647 | RSS: Main: 277340160 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | __start_tp_report 07:51:40 INFO - PID 1647 | _x_x_mozilla_page_load 07:51:40 INFO - PID 1647 | _x_x_mozilla_page_load_details 07:51:40 INFO - PID 1647 | |i|pagename|runs| 07:51:40 INFO - PID 1647 | |0;dojo.html;3115.3924984628;3559.1280136366577;3607.8196090195484;3609.133807886108;3624.746267761256;4886;5026.421961474432;6135.141080248769;6187.5668703190795;6231.750729970796;6151.969240153793;6190.195274614294;6217.564770466058;6255.186825712651;6295.653739044351;5247.239150323201;5405.378381486121;5435.266239057736;5445.428230035848;5537.363203231619;5567.192757050226;5570.637908535932;5571.665700057989;5575.135853942646;5577.8047768328115;5357.544608708265;5429.212764149216;5434.402215756264;5490.533304669108;5525.922445123203;5464.754086066137;5489.39616642169;5489.890202195934;5518.806841760519;5533.6403133796175;5389.67661940281;5441.891162176735;5447.918281225785;5464.098423760075;5487.396386397493;4608.516105808875;6121.877562448771;6127.080937859312;6236.033414820665;6251.843703907393;6477.676116194171;6569.540132190701;6696.531242813005;6705.463562914955;6719.496037797185;6802.557833740791;6805.863882722318;6813.9318606813795;6815.045893574904;6818.659067046628;6316.399942005502;6317.652529110943;6325.620462772248;6330.366963303679;6330.905036424411;5641.351244606841;6654.201495820533;6745.797626071226;6841.247462779091;6904.240533541307;5403.405625381205;6321.494280457552;6448.806535803933;6490.740370385131;6561.40947314744;6663.433608143362;6782.389584937379;6788;6802.319768023257;6802.965985170043;6677.933220667731;6680.198376194888;6681.532292739559;6688.063671086052;6702.430293425016;6023;6424.486041116699;6434.8391290218115;6528.804135875931;6560.507961902781;5310.654807437504;5334.91997620036;5373.113436283048;5396.865078373072;5398.352197736218;4810.63920205986;4813.085513752376;4818;4852.733099679481;4856.514348565115;5814.244148260699;6309.810705678837;6331.176946996953;6340.397662222082;6347.174867267226;5907.734151963172;6329.525285603599;6751.696173672197;6794.660266986631;6827.146606674166;6334.778282760081;6337.271213810379;6342.873142537123;6348.968255158695;6352.460040896483;5103.826119992423;5993.700314984233;6019.819405417845;6059.424354686298;6064.332923378425;5468.343798744176;6754.12196414463;6840.521163518506;6867.793966181022;6913.239543650195;6758.2565917748025;6816.693248803915;6849.657517124224;6856.108705868205;6866.141732283465;6919.792406227822;6929.099217101744;6933.895991560232;6951.930480695129;6960.443164546926;6485.805425837232;6567.540272180901;6587.110740050032;6666.266710661726;6698.765543205965;6762.763303284361;6801.931980680328;6818.28408017151;6832.624205668736;6850.0752398425575;6328.272248691275;6576.80269591913;6597.756762700513;6604.63674497888;6605.636689981905;6420.165378500765;6493.740250389931;6558.80323590293;6613.636250006103;6630.104935833793;6375.011873159668;6450.645214513247;6456.096146539582;6468.514861385321;6477.4494167996245;6578.8355291118105;6596.571222870498;6597.901031484435;6615.4376877965915;6620;5778.8266352009505;6280.623162610258;6301.054841773771;6396.200474940632;6408.29508754046 07:51:40 INFO - PID 1647 | |1;ext.html;672.3713328038284;817.7424111404907;836.5858899844576;1058.5765693722506;1224.173682764134;34606.48090278642;38256.61743382565;39058.804705976465;40089.398659020066;40162.7991860041;32449.83775081127;40050.39924401129;40541;40602;40648.98377540563;7866.606669666522;8015.599220038975;8367.790805229888;8590.570471476432;8698.826023479527;8428.53643049632;8523.786905327386;8569.414658288237;8580.828383432328;8599.312055035582;8502.107278735737;8508.702195423162;8543.487390756574;8585.484870907734;8612.741617751477;8461.280791132762;8530.616122274512;8546.829063418729;8561.22948934599;8577.099404562556;8276.131006244317;8353.665853365857;8391.496510209407;8403.957980210122;8405.621747021398;36543;36733.63266367329;36840;36947.4457883132;36983.815080924694;3544.8404821783083;3576.481410195507;3583.874564390234;3584.4085725855202;3587.336342776595;30656.693433065608;30717.539236911463;30729.846350768326;30733.231669208224;30849.845750771103;3118.8284644344553;3152.984235078833;3160.383725173592;3168.9683103168904;3169.825659588722;233914;237209.44185837227;281404;302452;304511;47320.76339618349;47637.04725905463;47656.5234347659;47668;47897;49738.75130624323;50002.499975000515;50115.49884501181;50175.494735158005;50244.49755502471;13874.722505550035;13975;14095.224762637949;14112.2943852809;14118.435262589586;11355.261907975955;11453.427328633536;11588.015018723334;11634.301941883514;11645.010174135294;13357.53248636293;13458.259795711134;13475.258860762557;13484.730305394034;13500.662483437993;6084.969575152096;6128.540359472968;6160.907586386208;6168.074788781719;6171.722272497658;932.8041111366554;1000.0549480740631;1006.4816619440847;1038.5274700011628;1055.720234137954;24365.025398984195;24517.509649806903;24575.87712061464;24685.5062898741;24729.25812225636;1100.5872797700863;1103.7626910214342;1140.4753813245834;1140.5437824870119;1140.6635042662435;12020.355521785194;12510.999120070372;12523.874761252453;12564.308963007123;12590.481903619313;320856;322078;322236;322957;323561;53988.730056349465;54212.72893635585;54257;54382.72808635931;54459.18311225335;1478.4308041404138;1523.5602355773908;1529.319452843474;1531.0737004112575;1532.0348180646122;21849.453763656034;23098.76901230966;24439.51120977606;24549;24608;33880;37767.866963991124;37818.62181378261;37820.43269351017;37925.86222413332;268468;268474;268738;268892;268988;32457.51313730249;33550.83224583862;33600.3279934395;34231.31537369289;34715.30569388648;36465.27069458649;42987.35518967156;44853.55146448624;45042.77478612586;45192.87017824581;23269.88365058164;27057.59413608837;28992.855035724686;30056.69943300539;31587;23621.409464763517;27479.17562473129;30499.695003049685;30653.386932261677;31301.57146420554;36846;37071.25857482889;37186.814065929495;37246;37486.812565937;28246.870125195583;29547.52262386915;29791.851040745525;30921;31324.686753132177;23078.653820192376;23493.53012939766;24014;24491.877540612182;24549.140780072612 07:51:40 INFO - PID 1647 | |2;jquery.html;426.3200195687877;431.2840684463787;437.6455071392173;450.31327225980374;456.16977101674956;19709.50726231845;21651.891740541294;22084.88957555214;22326.995285212164;22492.325230243077;23132.3060308191;24107.15624953127;24107.758922410772;24176;24197.032118715273;5881.3530511643685;6147.538934579924;6176.660283684396;6184.8453788655415;6191.380861913818;5908.438698323653;5965.701714914258;6008.218931538894;6027.789027384042;6036.909446358308;5963.075723262901;5976.252968378953;5983.311919129313;5985.670788106652;6000.3699611540815;5807.448292412235;5879.794207202749;5961.552883533753;5969.820905372824;6021.638701677914;5352.277442545245;5375.301210842605;5382.058139825514;5385.865353366158;5390.5418039466695;27240.59139112915;27690.723092769014;27795.58306625402;27829.860850695615;28706.569401458786;2169.9023543940402;2208.8564243324295;2220.6335954567476;2229.944251393712;2232.4084117708658;18154.455366339032;18297.908510457495;18309.084545772792;18615.53461163468;18716.532086697804;2153.881536515491;2174.3476956912864;2182.9345119646437;2209.1494774511766;2218.267971569378;151309;157617;159500.20249898793;159662.99999999884;159831;12465.314407707663;12647.177933434481;12760.6809829755;12771.489140434465;12861.356932153354;16547.83452165487;16620.836541442226;16627.667446650998;16664.00015999044;16702.164891755605;8790.95604521986;8984.101589841095;9078.182963533314;9141.177294043568;9210.677626283048;8507.744767656981;8601.01088374845;8639.395242333103;8772.956135219283;8798.12018798115;6750.561213521106;8053.234942680436;8147.429679922467;8188.426810123234;8325.000999879932;2136.5513242219;2147.3987283560627;2148.4745830460547;2152.1499007891794;2309.815214782813;941.8728471656239;966.4153187321709;967.398954729923;995.4027583450016;1008.8486726990864;21501.67747483789;21832;21894.233701820678;22000.899955002187;22140.55718885613;855.8587833007608;892.6875593542209;999.0758548342753;1023.9402218703606;1027.7790275090892;8708.69519566812;10821.134309255242;11163.72090697739;11184.776304473866;11222.270552414231;544146;547362;550346;550904;551674;115197.4240128794;124964;125868.1119783204;127804;128947;1078.8651418572679;1180.7874582575048;1194.9103817213743;1242.2174030360816;1248.4943597842707;18311.81688183101;21969.67045494351;22458.775412245668;22847.543049139258;23062.308130756104;48249;49082.75458622684;60672.78654426798;61185.47036324128;62643;63557.364426356995;83356.58321708352;83751;84091.5795421019;84555;8990.415622984747;9014.053524379846;9158.038405967278;9224.677136300197;9263.073692630684;14348.282585870873;14403.991720579459;14420.639484012563;14532.927335363256;14847.1834049124;11878.287302761863;12059.577914773292;12090.219249572823;12210.389480526115;12238;11197.38414387184;11409.315441073562;11458.484368203617;11804.881951180378;11885.584004560145;11260.324380537195;11933.82099268529;11955;12338.938305308417;12409.255444673348;7406.888966655045;8009.918660980693;8763.561821908752;8948.239399650842;9118.042605526589;11120.99911008013;11623.244489108181;11629.011534019703;12005.219660722374;12030.699232519257 07:51:40 INFO - PID 1647 | |3;mootools.html;1272.8536218334891;1475.0928179169814;1478.7707905274679;1481.3556103095154;1490.1059364381372;11594.826077608832;12006.819897701542;12182.329971851555;12186.025117990574;12216.267023978568;12135.514579416835;12324.507019719224;12328.938355308233;12336.19814712043;12355.752884942296;2545.3382120648625;2574.909878154265;2593.8832752526087;2599.454114635929;2600.804939629526;2526.2295000024983;2552.349150966501;2573.90991315304;2576.6392705021312;2578.561644520436;2158.98920505398;2538.6192071189375;2545.5036267927762;2549.2097449790626;2550.2349295211457;2484.8757562122;2494.3140636324974;2512.9371765705914;2519;2520.193538067819;1998.7901270366692;2286.6965829477203;2430.890609922558;2494.4262819551423;2507.924762257135;11876.643700688994;11979.580714674945;11982.760344793056;12003.039756819433;12027;6088.969555152196;6302.023186406114;6755.560888542229;6767.661616919135;6788.185417749853;4035.293823580891;4202.85290014848;4298.011457364823;4304.332828411601;4304.78476076195;1193.653840386287;1203.272020427646;1230.864604893461;1240.925544467335;1261.3504045416619;15269.160196189205;15574.688506229812;16436.75344869828;16749.91625041879;16881.155942202964;5458.972705136449;5513.5037846594;5591.832245032656;5649.887002259932;5713.085906255063;11904.166708330507;11932.164748467523;11934.820977685342;11963.46164422603;11992.520299187934;6359.2368915729485;6416.775412860621;6506.25178104524;6508.772192973223;6517.706703198368;6049.879002420015;6051.546134039964;6092.482139018144;6122.26532816056;6194.81415557534;4163.521195062546;4253.468316460442;4313.676474264443;4341.240282950471;4354.629856462173;1221.694576355911;1234.0682784497646;1246.6820960654975;1249.1006475337742;1252.2361359570655;776.9123227481571;777.6928113395177;792.8691765858568;801.943863929531;802.2819576479135;6438.935610643834;6642.966785166043;6934.16789985205;7004.640487001626;7008.299170083053;778.7118766056595;842.6587232170981;871.9476831390136;879.7096958003843;939.910708482693;3845.2117315950723;3943.290207762573;3944.230874979409;4332.891677708082;4452.465704115462;11844.88155118455;15371.692566148615;15456.84543154554;15565.688686226213;15603.765943510856;13897.54075822024;14387.352569134415;15435.30541125652;15480.071195728293;15521.611959701098;8249.175082491704;8744.213020828156;8856.955715221382;8886.777830554158;8920.509371984603;6113.908291375722;6786.932130678828;6817.829554261183;7112.395446387115;7118.822029449305;12897.677558061123;13126.803097953729;13288.933555332162;13290.534831280858;13325.267110309027;12955.611331660066;13226.4048117833;13428.0600357974;13521.86478135206;13532.93233533826;9379.81240375175;9463.858042129512;9508.096730810563;9563.043695630382;9579.952100239454;10603.257771955889;10609.89390106089;10735.838962415726;10742.73143171427;10743.979321964402;9073.954630226806;9195.310351723514;9202.493862837608;9238.538073096184;9240.306976976619;8881.73354799357;9031.277497800029;9096.317776166947;9097.954510227672;9162.633494660138;8283.337333013224;8390.538520381439;8402.369822263232;8403.201695838885;8422.115677853737;6005.3694362091355;6017.909731353946;6087.239095113111;6174.660393678211;6228.5951413158;6040.214772079602;6077.4834139098675;6154.692265386802;6226.501879849692;6358.9015624844615 07:51:40 INFO - PID 1647 | |4;prototype.html;400.9258287216865;404.36514671965006;404.85829959514166;408.89777555611096;409.95080590329167;6316.810495685131;6350.523710721691;6358.601107756298;6387.201599800025;6409.711562979672;4600.802442302601;5654.858628534278;6187.319394866561;6230.971889638205;6270.65511396873;3901.7709421532268;4484.394606728099;4704.694194877331;4746.430428348603;4769.92845107322;4440.911181776362;4452.9980754330345;4707.846577588495;4778.283257511383;4786.3059856320815;4675.158471475133;4694.765261736899;4711.528847115295;4720.03239335937;4729.4797572267025;3711.6473934976134;4198.706090573661;4664.696794708333;4697.224957881946;4734.029523947583;3814.9152102396633;4002.679785617144;4110;4159.9792001039805;4162.687798415101;5291.94161167768;5873.177755114245;6023.759049638009;6068.757249710006;6119.66341851198;5548.72256387179;5594.580406469531;5595.18869763882;5617.820257745878;5665.886682266373;5504.944950550484;5577.581681373913;6684.364985326386;6742.797716068526;6755.864882702318;4685.906281874378;4710.175719249152;4711.858644240679;4716.976415117937;4762.78567464465;5901.321348044944;6108.96945515274;6740.595564266159;6755.324467553254;6782.321767823228;3766.8681596144;4682.531746825358;4693.296005599119;4958.231474121517;4966.081274964144;4433.933490997638;4790.976045119752;5036.64743467961;5061.9493805061475;5064.037832811754;4854.878628034327;5641;5672.4894759471845;5683.147527870769;5695.487406133468;5815.360310365856;6669.833254168588;6696.560239548423;6696.732130714814;6709.798706038729;1722.6210233748554;1725.464336740302;1735.8437740603406;1737.1314342828587;1752.9474115776547;960.6685693435753;1098.6703988803326;1173.4543437301695;1174.4656181437426;1191.642507247822;6199.318075011745;6246.250449946036;6246.718897649616;6276.811695649138;6302.558820882494;5349.646155691484;6436.935630643728;6903.861922761516;6916.55042422241;6935.959606059131;5068.935523540098;5343.385510666245;6119.938800612026;6141.692915354214;6247.968760156261;3239.271163988131;3279.098247981824;3450.2236996675547;3621.587580843473;3650.8904732858055;5914.34942156371;6508.8047358579315;6671.833204169935;7070.621228860426;7205.531640443354;6245.313015568284;7019.5788252705;7142.464315176382;7142.750003749844;7216.809226477572;5535.750891209906;6257.906131408033;6863.161471162818;7146.892796608055;7189.784306470814;6568.934310656832;6908.3437073477935;6975.372216500539;7031.542949708252;7092.893606596009;6642.966785166043;6860.725570977105;6863.416609588241;6903.89644155328;6908.1019467468905;7197.568145911262;7204.71181152748;7206.603636799817;7208.387287080657;7211.3870321023305;3622.583402908699;4361.3676016978;4443.755593442392;4458.799354029012;4463.152001119777;6953.582785032914;6975.267596902252;6994.2656021117045;7008.088948436671;7246.456515761444;6185.927714215608;6247.781327653511;6456.903146452713;6507.837304067247;6778.389944904785;5907.497862681548;6048.852657306768;6215.751369945152;6701.463882889455;6704.793137235344;5697.003024470619;6419.261784894797;6558.508111891531;6573.506986976092;6580.210374754868;6066.181065556093;6361.681915904093;6683.794347865096;6694;6730.865382692221;5122.359705036871;5512.6692398456225;6369.617822930639;6570.178727659043;6587.80236592903 07:51:40 INFO - PID 1647 | |5;yui.html;837.3928901546376;842.3135144856941;852.7058164933098;855.4525103933483;878.9121087891211;17032.744508832362;17285.740713889285;17449.563760905985;19419.417417477463;20163.09266083026;18538.81461185388;18685.532861678465;18740.969246691428;18795.060246987665;18821.341253056147;996.7956568903394;1011.0849681038671;1024.3085917006028;1029.8043371759343;1030.0317701360734;1003.2124782046059;1022;1028.311031608824;1032.060824649569;1032.143321043532;1054.9525271362807;1083.6207327435413;1085.3867564825887;1087.8205096159124;1090.514720949178;1064.77639695664;1082.7563798145393;1083.4474418046814;1085.733995171188;1094.7044298039484;777.7161336112334;801.0827602395283;801.7033697531892;803.0724513187278;803.3292201012162;17456.563585910328;18008.639827203515;18168.273269069352;18217.362392316205;18314;17273.481795546155;17712.468625941114;17775.111244437856;18149.7277540837;18149.818501814945;14368.066075705046;14703.926480367636;14884.404623815035;14887;14907.701845963129;3453.689167974869;3533.770304930197;3534.7525673202886;3540.0087975366937;3543.769654972444;156566;156713;156714;157024;157455;12074.456649450796;13435;13481.325933703472;13492.460301588022;13709;25901.83441745126;26721.599176012376;26807.731922680523;27025.729742702322;27066.729332706815;7558.206388329256;7566;7586.886196707053;7658.923410765821;7666.616669166519;3809.219110082482;3889.163829776555;3898.863539776094;3901.08324543732;3924.2151569685607;4381.123775244963;4399.934000989988;4453.955460445354;4460.8661740147845;4464.2633965396;866.7876370289193;881.9857164261151;895.1227796759213;896.4218079338921;911.6763548940031;643.240975648739;658.5127006015609;665.2084020016165;671.660811290305;681.4752640466813;9462.90537094634;9946.35348702332;11848.703782405337;11905.107116966263;12371.876281237252;636.4113195294334;639.9936000640027;649.7920665387031;652.7650045983443;668.3583759590847;4950.158473059589;5585.273914391184;5774.364819869811;5779.335376431764;5792.478676919098;145310.27344863207;145740;145845;148079.25960370345;149585;124805.37597311955;125577;126797;129353;130419;83901;108147.99999999843;108614;110054;111766;11196.496157673088;11434.542618295507;11787.292762434281;12484.937575312066;12653.493860245488;36400;39329.016774580865;41448;42972;43560;68844.6557767208;71622;83966.16033839827;84201;85114;16942.915285423493;17371.826281737525;17608.911955440653;17712.734308985633;17750.556236094202;18773.249070037593;18868.43394698161;18953.14710837988;18990.43028709141;19059.237630494627;17915.283388664306;17943;18044.90977545104;18080.81919180844;18230.908845455688;17222.655546889244;17810.91094544519;17849.286028558712;18165.636687266444;18284.268629255213;14981.925090374914;16760.832391675925;17543.035133067293;17690.823091768918;17851.46445606634;11671.824922625998;11700.648980530597;12080;12207.938960305142;12289.324087175291;12066.517339306329;13439;13559.728805424034;13566.050376473551;13628.454861805416 07:51:40 INFO - PID 1647 | __end_tp_report 07:51:40 INFO - PID 1647 | __start_cc_report 07:51:40 INFO - PID 1647 | _x_x_mozilla_cycle_collect,401 07:51:40 INFO - PID 1647 | __end_cc_report 07:51:40 INFO - PID 1647 | __startTimestamp1495723900662__endTimestamp 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | ------- Summary: start ------- 07:51:40 INFO - PID 1647 | Number of tests: 6 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6131.52 Median:6342.87 stddev:746.76 (11.8%) stddev-sans-first:710.58 07:51:40 INFO - PID 1647 | Values: 3115.4 3559.1 3607.8 3609.1 3624.7 4886.0 5026.4 6135.1 6187.6 6231.8 6152.0 6190.2 6217.6 6255.2 6295.7 5247.2 5405.4 5435.3 5445.4 5537.4 5567.2 5570.6 5571.7 5575.1 5577.8 5357.5 5429.2 5434.4 5490.5 5525.9 5464.8 5489.4 5489.9 5518.8 5533.6 5389.7 5441.9 5447.9 5464.1 5487.4 4608.5 6121.9 6127.1 6236.0 6251.8 6477.7 6569.5 6696.5 6705.5 6719.5 6802.6 6805.9 6813.9 6815.0 6818.7 6316.4 6317.7 6325.6 6330.4 6330.9 5641.4 6654.2 6745.8 6841.2 6904.2 5403.4 6321.5 6448.8 6490.7 6561.4 6663.4 6782.4 6788.0 6802.3 6803.0 6677.9 6680.2 6681.5 6688.1 6702.4 6023.0 6424.5 6434.8 6528.8 6560.5 5310.7 5334.9 5373.1 5396.9 5398.4 4810.6 4813.1 4818.0 4852.7 4856.5 5814.2 6309.8 6331.2 6340.4 6347.2 5907.7 6329.5 6751.7 6794.7 6827.1 6334.8 6337.3 6342.9 6349.0 6352.5 5103.8 5993.7 6019.8 6059.4 6064.3 5468.3 6754.1 6840.5 6867.8 6913.2 6758.3 6816.7 6849.7 6856.1 6866.1 6919.8 6929.1 6933.9 6951.9 6960.4 6485.8 6567.5 6587.1 6666.3 6698.8 6762.8 6801.9 6818.3 6832.6 6850.1 6328.3 6576.8 6597.8 6604.6 6605.6 6420.2 6493.7 6558.8 6613.6 6630.1 6375.0 6450.6 6456.1 6468.5 6477.4 6578.8 6596.6 6597.9 6615.4 6620.0 5778.8 6280.6 6301.1 6396.2 6408.3 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:43912.80 Median:178005.42 stddev:75800.05 (42.6%) stddev-sans-first:75943.16 07:51:40 INFO - PID 1647 | Values: 672.4 817.7 836.6 1058.6 1224.2 34606.5 38256.6 39058.8 40089.4 40162.8 32449.8 40050.4 40541.0 40602.0 40649.0 7866.6 8015.6 8367.8 8590.6 8698.8 8428.5 8523.8 8569.4 8580.8 8599.3 8502.1 8508.7 8543.5 8585.5 8612.7 8461.3 8530.6 8546.8 8561.2 8577.1 8276.1 8353.7 8391.5 8404.0 8405.6 36543.0 36733.6 36840.0 36947.4 36983.8 3544.8 3576.5 3583.9 3584.4 3587.3 30656.7 30717.5 30729.8 30733.2 30849.8 3118.8 3153.0 3160.4 3169.0 3169.8 233914.0 237209.4 281404.0 302452.0 304511.0 47320.8 47637.0 47656.5 47668.0 47897.0 49738.8 50002.5 50115.5 50175.5 50244.5 13874.7 13975.0 14095.2 14112.3 14118.4 11355.3 11453.4 11588.0 11634.3 11645.0 13357.5 13458.3 13475.3 13484.7 13500.7 6085.0 6128.5 6160.9 6168.1 6171.7 932.8 1000.1 1006.5 1038.5 1055.7 24365.0 24517.5 24575.9 24685.5 24729.3 1100.6 1103.8 1140.5 1140.5 1140.7 12020.4 12511.0 12523.9 12564.3 12590.5 320856.0 322078.0 322236.0 322957.0 323561.0 53988.7 54212.7 54257.0 54382.7 54459.2 1478.4 1523.6 1529.3 1531.1 1532.0 21849.5 23098.8 24439.5 24549.0 24608.0 33880.0 37767.9 37818.6 37820.4 37925.9 268468.0 268474.0 268738.0 268892.0 268988.0 32457.5 33550.8 33600.3 34231.3 34715.3 36465.3 42987.4 44853.6 45042.8 45192.9 23269.9 27057.6 28992.9 30056.7 31587.0 23621.4 27479.2 30499.7 30653.4 31301.6 36846.0 37071.3 37186.8 37246.0 37486.8 28246.9 29547.5 29791.9 30921.0 31324.7 23078.7 23493.5 24014.0 24491.9 24549.1 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:35755.95 Median:12686.06 stddev:93100.24 (733.9%) stddev-sans-first:93323.62 07:51:40 INFO - PID 1647 | Values: 426.3 431.3 437.6 450.3 456.2 19709.5 21651.9 22084.9 22327.0 22492.3 23132.3 24107.2 24107.8 24176.0 24197.0 5881.4 6147.5 6176.7 6184.8 6191.4 5908.4 5965.7 6008.2 6027.8 6036.9 5963.1 5976.3 5983.3 5985.7 6000.4 5807.4 5879.8 5961.6 5969.8 6021.6 5352.3 5375.3 5382.1 5385.9 5390.5 27240.6 27690.7 27795.6 27829.9 28706.6 2169.9 2208.9 2220.6 2229.9 2232.4 18154.5 18297.9 18309.1 18615.5 18716.5 2153.9 2174.3 2182.9 2209.1 2218.3 151309.0 157617.0 159500.2 159663.0 159831.0 12465.3 12647.2 12760.7 12771.5 12861.4 16547.8 16620.8 16627.7 16664.0 16702.2 8791.0 8984.1 9078.2 9141.2 9210.7 8507.7 8601.0 8639.4 8773.0 8798.1 6750.6 8053.2 8147.4 8188.4 8325.0 2136.6 2147.4 2148.5 2152.1 2309.8 941.9 966.4 967.4 995.4 1008.8 21501.7 21832.0 21894.2 22000.9 22140.6 855.9 892.7 999.1 1023.9 1027.8 8708.7 10821.1 11163.7 11184.8 11222.3 544146.0 547362.0 550346.0 550904.0 551674.0 115197.4 124964.0 125868.1 127804.0 128947.0 1078.9 1180.8 1194.9 1242.2 1248.5 18311.8 21969.7 22458.8 22847.5 23062.3 48249.0 49082.8 60672.8 61185.5 62643.0 63557.4 83356.6 83751.0 84091.6 84555.0 8990.4 9014.1 9158.0 9224.7 9263.1 14348.3 14404.0 14420.6 14532.9 14847.2 11878.3 12059.6 12090.2 12210.4 12238.0 11197.4 11409.3 11458.5 11804.9 11885.6 11260.3 11933.8 11955.0 12338.9 12409.3 7406.9 8009.9 8763.6 8948.2 9118.0 11121.0 11623.2 11629.0 12005.2 12030.7 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7150.25 Median:3894.25 stddev:4512.54 (115.9%) stddev-sans-first:4503.58 07:51:40 INFO - PID 1647 | Values: 1272.9 1475.1 1478.8 1481.4 1490.1 11594.8 12006.8 12182.3 12186.0 12216.3 12135.5 12324.5 12328.9 12336.2 12355.8 2545.3 2574.9 2593.9 2599.5 2600.8 2526.2 2552.3 2573.9 2576.6 2578.6 2159.0 2538.6 2545.5 2549.2 2550.2 2484.9 2494.3 2512.9 2519.0 2520.2 1998.8 2286.7 2430.9 2494.4 2507.9 11876.6 11979.6 11982.8 12003.0 12027.0 6089.0 6302.0 6755.6 6767.7 6788.2 4035.3 4202.9 4298.0 4304.3 4304.8 1193.7 1203.3 1230.9 1240.9 1261.4 15269.2 15574.7 16436.8 16749.9 16881.2 5459.0 5513.5 5591.8 5649.9 5713.1 11904.2 11932.2 11934.8 11963.5 11992.5 6359.2 6416.8 6506.3 6508.8 6517.7 6049.9 6051.5 6092.5 6122.3 6194.8 4163.5 4253.5 4313.7 4341.2 4354.6 1221.7 1234.1 1246.7 1249.1 1252.2 776.9 777.7 792.9 801.9 802.3 6438.9 6643.0 6934.2 7004.6 7008.3 778.7 842.7 871.9 879.7 939.9 3845.2 3943.3 3944.2 4332.9 4452.5 11844.9 15371.7 15456.8 15565.7 15603.8 13897.5 14387.4 15435.3 15480.1 15521.6 8249.2 8744.2 8857.0 8886.8 8920.5 6113.9 6786.9 6817.8 7112.4 7118.8 12897.7 13126.8 13288.9 13290.5 13325.3 12955.6 13226.4 13428.1 13521.9 13532.9 9379.8 9463.9 9508.1 9563.0 9580.0 10603.3 10609.9 10735.8 10742.7 10744.0 9074.0 9195.3 9202.5 9238.5 9240.3 8881.7 9031.3 9096.3 9098.0 9162.6 8283.3 8390.5 8402.4 8403.2 8422.1 6005.4 6017.9 6087.2 6174.7 6228.6 6040.2 6077.5 6154.7 6226.5 6358.9 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5397.03 Median:5969.05 stddev:1659.56 (27.8%) stddev-sans-first:1621.30 07:51:40 INFO - PID 1647 | Values: 400.9 404.4 404.9 408.9 410.0 6316.8 6350.5 6358.6 6387.2 6409.7 4600.8 5654.9 6187.3 6231.0 6270.7 3901.8 4484.4 4704.7 4746.4 4769.9 4440.9 4453.0 4707.8 4778.3 4786.3 4675.2 4694.8 4711.5 4720.0 4729.5 3711.6 4198.7 4664.7 4697.2 4734.0 3814.9 4002.7 4110.0 4160.0 4162.7 5291.9 5873.2 6023.8 6068.8 6119.7 5548.7 5594.6 5595.2 5617.8 5665.9 5504.9 5577.6 6684.4 6742.8 6755.9 4685.9 4710.2 4711.9 4717.0 4762.8 5901.3 6109.0 6740.6 6755.3 6782.3 3766.9 4682.5 4693.3 4958.2 4966.1 4433.9 4791.0 5036.6 5061.9 5064.0 4854.9 5641.0 5672.5 5683.1 5695.5 5815.4 6669.8 6696.6 6696.7 6709.8 1722.6 1725.5 1735.8 1737.1 1752.9 960.7 1098.7 1173.5 1174.5 1191.6 6199.3 6246.3 6246.7 6276.8 6302.6 5349.6 6436.9 6903.9 6916.6 6936.0 5068.9 5343.4 6119.9 6141.7 6248.0 3239.3 3279.1 3450.2 3621.6 3650.9 5914.3 6508.8 6671.8 7070.6 7205.5 6245.3 7019.6 7142.5 7142.8 7216.8 5535.8 6257.9 6863.2 7146.9 7189.8 6568.9 6908.3 6975.4 7031.5 7092.9 6643.0 6860.7 6863.4 6903.9 6908.1 7197.6 7204.7 7206.6 7208.4 7211.4 3622.6 4361.4 4443.8 4458.8 4463.2 6953.6 6975.3 6994.3 7008.1 7246.5 6185.9 6247.8 6456.9 6507.8 6778.4 5907.5 6048.9 6215.8 6701.5 6704.8 5697.0 6419.3 6558.5 6573.5 6580.2 6066.2 6361.7 6683.8 6694.0 6730.9 5122.4 5512.7 6369.6 6570.2 6587.8 07:51:40 INFO - PID 1647 | 07:51:40 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:26481.44 Median:18115.27 stddev:41243.54 (227.7%) stddev-sans-first:41314.29 07:51:40 INFO - PID 1647 | Values: 837.4 842.3 852.7 855.5 878.9 17032.7 17285.7 17449.6 19419.4 20163.1 18538.8 18685.5 18741.0 18795.1 18821.3 996.8 1011.1 1024.3 1029.8 1030.0 1003.2 1022.0 1028.3 1032.1 1032.1 1055.0 1083.6 1085.4 1087.8 1090.5 1064.8 1082.8 1083.4 1085.7 1094.7 777.7 801.1 801.7 803.1 803.3 17456.6 18008.6 18168.3 18217.4 18314.0 17273.5 17712.5 17775.1 18149.7 18149.8 14368.1 14703.9 14884.4 14887.0 14907.7 3453.7 3533.8 3534.8 3540.0 3543.8 156566.0 156713.0 156714.0 157024.0 157455.0 12074.5 13435.0 13481.3 13492.5 13709.0 25901.8 26721.6 26807.7 27025.7 27066.7 7558.2 7566.0 7586.9 7658.9 7666.6 3809.2 3889.2 3898.9 3901.1 3924.2 4381.1 4399.9 4454.0 4460.9 4464.3 866.8 882.0 895.1 896.4 911.7 643.2 658.5 665.2 671.7 681.5 9462.9 9946.4 11848.7 11905.1 12371.9 636.4 640.0 649.8 652.8 668.4 4950.2 5585.3 5774.4 5779.3 5792.5 145310.3 145740.0 145845.0 148079.3 149585.0 124805.4 125577.0 126797.0 129353.0 130419.0 83901.0 108148.0 108614.0 110054.0 111766.0 11196.5 11434.5 11787.3 12484.9 12653.5 36400.0 39329.0 41448.0 42972.0 43560.0 68844.7 71622.0 83966.2 84201.0 85114.0 16942.9 17371.8 17608.9 17712.7 17750.6 18773.2 18868.4 18953.1 18990.4 19059.2 17915.3 17943.0 18044.9 18080.8 18230.9 17222.7 17810.9 17849.3 18165.6 18284.3 14981.9 16760.8 17543.0 17690.8 17851.5 11671.8 11700.6 12080.0 12207.9 12289.3 12066.5 13439.0 13559.7 13566.1 13628.5 07:51:40 INFO - PID 1647 | -------- Summary: end -------- 07:51:40 INFO - PID 1647 | 07:51:41 INFO - TEST-INFO | 1647: exit 0 07:51:41 INFO - TEST-OK | dromaeo_css | took 1143074ms 07:51:41 INFO - TEST-START | kraken 07:51:41 INFO - operating with platform_type : mac_ 07:51:41 INFO - Initialising browser for kraken test... 07:51:41 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpof01k8/profile 07:51:41 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 07:52:14 INFO - TEST-INFO | 1655: killed by SIGTERM 07:52:14 INFO - Browser initialized. 07:52:14 INFO - Running cycle 1/1 for kraken test... 07:52:14 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/tmpof01k8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:16 INFO - PID 1659 | 2017-05-25 07:52:16.728 plugin-container[1660:10778] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 07:52:16 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:16 INFO - PID 1659 | 2017-05-25 07:52:16.728 plugin-container[1660:10778] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 07:52:16 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:16 INFO - PID 1659 | 2017-05-25 07:52:16.728 plugin-container[1660:10778] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:17 INFO - PID 1659 | 2017-05-25 07:52:17.241 plugin-container[1661:10823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474f, name = 'com.apple.tsm.portname' 07:52:17 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:17 INFO - PID 1659 | 2017-05-25 07:52:17.242 plugin-container[1661:10823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490f, name = 'com.apple.CFPasteboardClient' 07:52:17 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:52:17 INFO - PID 1659 | 2017-05-25 07:52:17.242 plugin-container[1661:10823] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:52:26 INFO - PID 1659 | RSS: Main: 245555200 07:52:26 INFO - PID 1659 | 07:52:29 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:30 INFO - PID 1659 | RSS: Main: 259981312 07:52:30 INFO - PID 1659 | 07:52:32 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 07:52:33 INFO - PID 1659 | RSS: Main: 269361152 07:52:33 INFO - PID 1659 | 07:52:36 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 07:52:37 INFO - PID 1659 | RSS: Main: 271941632 07:52:37 INFO - PID 1659 | 07:52:39 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 07:52:39 INFO - PID 1659 | RSS: Main: 273104896 07:52:39 INFO - PID 1659 | 07:52:41 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:42 INFO - PID 1659 | RSS: Main: 273321984 07:52:42 INFO - PID 1659 | 07:52:45 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 07:52:45 INFO - PID 1659 | RSS: Main: 279638016 07:52:45 INFO - PID 1659 | 07:52:48 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:49 INFO - PID 1659 | RSS: Main: 282599424 07:52:49 INFO - PID 1659 | 07:52:52 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 07:52:53 INFO - PID 1659 | RSS: Main: 281178112 07:52:53 INFO - PID 1659 | 07:52:54 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:55 INFO - PID 1659 | RSS: Main: 280846336 07:52:55 INFO - PID 1659 | 07:52:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:52:57 INFO - PID 1659 | RSS: Main: 280739840 07:52:57 INFO - PID 1659 | 07:52:59 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:53:00 INFO - PID 1659 | RSS: Main: 280764416 07:53:00 INFO - PID 1659 | 07:53:03 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:53:03 INFO - PID 1659 | RSS: Main: 280330240 07:53:03 INFO - PID 1659 | 07:53:07 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:53:07 INFO - PID 1659 | RSS: Main: 280543232 07:53:07 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 07:53:09 INFO - PID 1659 | RSS: Main: 282210304 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | __start_tp_report 07:53:09 INFO - PID 1659 | _x_x_mozilla_page_load 07:53:09 INFO - PID 1659 | _x_x_mozilla_page_load_details 07:53:09 INFO - PID 1659 | |i|pagename|runs| 07:53:09 INFO - PID 1659 | |0;ai-astar;103;167;128;115;116;115;115;116;114;115 07:53:09 INFO - PID 1659 | |1;audio-beat-detection;145;109;119;173;143;140;112;107;135;110 07:53:09 INFO - PID 1659 | |2;audio-dft;191;201;197;235;259;244;199;195;198;196 07:53:09 INFO - PID 1659 | |3;audio-fft;79;59;59;82;58;56;61;60;74;60 07:53:09 INFO - PID 1659 | |4;audio-oscillator;90;70;82;71;68;69;90;70;70;102 07:53:09 INFO - PID 1659 | |5;imaging-gaussian-blur;88;89;89;88;90;88;90;89;88;95 07:53:09 INFO - PID 1659 | |6;imaging-darkroom;96;96;96;96;97;96;96;107;110;97 07:53:09 INFO - PID 1659 | |7;imaging-desaturate;92;110;105;93;89;90;90;122;89;95 07:53:09 INFO - PID 1659 | |8;json-parse-financial;51;49;47;49;53;51;52;49;48;48 07:53:09 INFO - PID 1659 | |9;json-stringify-tinderbox;58;56;58;60;66;59;54;57;54;55 07:53:09 INFO - PID 1659 | |10;stanford-crypto-aes;87;87;87;85;84;88;87;86;139;116 07:53:09 INFO - PID 1659 | |11;stanford-crypto-ccm;136;170;148;134;136;137;135;139;152;141 07:53:09 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;187;187;171;236;231;217;234;226;191;186 07:53:09 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;65;68;68;68;65;67;68;67;69;68 07:53:09 INFO - PID 1659 | __end_tp_report 07:53:09 INFO - PID 1659 | __start_cc_report 07:53:09 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1056 07:53:09 INFO - PID 1659 | __end_cc_report 07:53:09 INFO - PID 1659 | __startTimestamp1495723989817__endTimestamp 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | ------- Summary: start ------- 07:53:09 INFO - PID 1659 | Number of tests: 14 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:120.40 Median:115.50 stddev:17.41 (15.1%) stddev-sans-first:17.29 07:53:09 INFO - PID 1659 | Values: 103.0 167.0 128.0 115.0 116.0 115.0 115.0 116.0 114.0 115.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:137.50 stddev:21.55 (15.7%) stddev-sans-first:22.09 07:53:09 INFO - PID 1659 | Values: 145.0 109.0 119.0 173.0 143.0 140.0 112.0 107.0 135.0 110.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:211.50 Median:200.00 stddev:24.62 (12.3%) stddev-sans-first:24.97 07:53:09 INFO - PID 1659 | Values: 191.0 201.0 197.0 235.0 259.0 244.0 199.0 195.0 198.0 196.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:64.80 Median:60.50 stddev:9.62 (15.9%) stddev-sans-first:8.73 07:53:09 INFO - PID 1659 | Values: 79.0 59.0 59.0 82.0 58.0 56.0 61.0 60.0 74.0 60.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:70.50 stddev:12.03 (17.1%) stddev-sans-first:11.97 07:53:09 INFO - PID 1659 | Values: 90.0 70.0 82.0 71.0 68.0 69.0 90.0 70.0 70.0 102.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:89.00 stddev:2.12 (2.4%) stddev-sans-first:2.19 07:53:09 INFO - PID 1659 | Values: 88.0 89.0 89.0 88.0 90.0 88.0 90.0 89.0 88.0 95.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:96.00 stddev:5.23 (5.4%) stddev-sans-first:5.45 07:53:09 INFO - PID 1659 | Values: 96.0 96.0 96.0 96.0 97.0 96.0 96.0 107.0 110.0 97.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:97.50 Median:90.00 stddev:11.19 (12.4%) stddev-sans-first:11.69 07:53:09 INFO - PID 1659 | Values: 92.0 110.0 105.0 93.0 89.0 90.0 90.0 122.0 89.0 95.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:1.95 (3.9%) stddev-sans-first:2.01 07:53:09 INFO - PID 1659 | Values: 51.0 49.0 47.0 49.0 53.0 51.0 52.0 49.0 48.0 48.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:58.00 stddev:3.56 (6.1%) stddev-sans-first:3.77 07:53:09 INFO - PID 1659 | Values: 58.0 56.0 58.0 60.0 66.0 59.0 54.0 57.0 54.0 55.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:94.60 Median:87.00 stddev:18.20 (20.9%) stddev-sans-first:19.10 07:53:09 INFO - PID 1659 | Values: 87.0 87.0 87.0 85.0 84.0 88.0 87.0 86.0 139.0 116.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:140.00 stddev:11.22 (8.0%) stddev-sans-first:11.63 07:53:09 INFO - PID 1659 | Values: 136.0 170.0 148.0 134.0 136.0 137.0 135.0 139.0 152.0 141.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.60 Median:221.50 stddev:24.49 (11.1%) stddev-sans-first:24.93 07:53:09 INFO - PID 1659 | Values: 187.0 187.0 171.0 236.0 231.0 217.0 234.0 226.0 191.0 186.0 07:53:09 INFO - PID 1659 | 07:53:09 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.13 07:53:09 INFO - PID 1659 | Values: 65.0 68.0 68.0 68.0 65.0 67.0 68.0 67.0 69.0 68.0 07:53:09 INFO - PID 1659 | -------- Summary: end -------- 07:53:09 INFO - PID 1659 | 07:53:10 INFO - TEST-INFO | 1659: exit 0 07:53:10 INFO - TEST-OK | kraken | took 89319ms 07:53:10 INFO - SUITE-END | took 1232s 07:53:10 INFO - Completed test suite (00:20:32) 07:53:10 INFO - javascript benchmark 07:53:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8005.351737285946, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3115.3924984628, 3559.1280136366577, 3607.8196090195484, 3609.133807886108, 3624.746267761256, 4886.0, 5026.421961474432, 6135.141080248769, 6187.5668703190795, 6231.750729970796, 6151.969240153793, 6190.195274614294, 6217.564770466058, 6255.186825712651, 6295.653739044351, 5247.239150323201, 5405.378381486121, 5435.266239057736, 5445.428230035848, 5537.363203231619, 5567.192757050226, 5570.637908535932, 5571.665700057989, 5575.135853942646, 5577.8047768328115, 5357.544608708265, 5429.212764149216, 5434.402215756264, 5490.533304669108, 5525.922445123203, 5464.754086066137, 5489.39616642169, 5489.890202195934, 5518.806841760519, 5533.6403133796175, 5389.67661940281, 5441.891162176735, 5447.918281225785, 5464.098423760075, 5487.396386397493, 4608.516105808875, 6121.877562448771, 6127.080937859312, 6236.033414820665, 6251.843703907393, 6477.676116194171, 6569.540132190701, 6696.531242813005, 6705.463562914955, 6719.496037797185, 6802.557833740791, 6805.863882722318, 6813.9318606813795, 6815.045893574904, 6818.659067046628, 6316.399942005502, 6317.652529110943, 6325.620462772248, 6330.366963303679, 6330.905036424411, 5641.351244606841, 6654.201495820533, 6745.797626071226, 6841.247462779091, 6904.240533541307, 5403.405625381205, 6321.494280457552, 6448.806535803933, 6490.740370385131, 6561.40947314744, 6663.433608143362, 6782.389584937379, 6788.0, 6802.319768023257, 6802.965985170043, 6677.933220667731, 6680.198376194888, 6681.532292739559, 6688.063671086052, 6702.430293425016, 6023.0, 6424.486041116699, 6434.8391290218115, 6528.804135875931, 6560.507961902781, 5310.654807437504, 5334.91997620036, 5373.113436283048, 5396.865078373072, 5398.352197736218, 4810.63920205986, 4813.085513752376, 4818.0, 4852.733099679481, 4856.514348565115, 5814.244148260699, 6309.810705678837, 6331.176946996953, 6340.397662222082, 6347.174867267226, 5907.734151963172, 6329.525285603599, 6751.696173672197, 6794.660266986631, 6827.146606674166, 6334.778282760081, 6337.271213810379, 6342.873142537123, 6348.968255158695, 6352.460040896483, 5103.826119992423, 5993.700314984233, 6019.819405417845, 6059.424354686298, 6064.332923378425, 5468.343798744176, 6754.12196414463, 6840.521163518506, 6867.793966181022, 6913.239543650195, 6758.2565917748025, 6816.693248803915, 6849.657517124224, 6856.108705868205, 6866.141732283465, 6919.792406227822, 6929.099217101744, 6933.895991560232, 6951.930480695129, 6960.443164546926, 6485.805425837232, 6567.540272180901, 6587.110740050032, 6666.266710661726, 6698.765543205965, 6762.763303284361, 6801.931980680328, 6818.28408017151, 6832.624205668736, 6850.0752398425575, 6328.272248691275, 6576.80269591913, 6597.756762700513, 6604.63674497888, 6605.636689981905, 6420.165378500765, 6493.740250389931, 6558.80323590293, 6613.636250006103, 6630.104935833793, 6375.011873159668, 6450.645214513247, 6456.096146539582, 6468.514861385321, 6477.4494167996245, 6578.8355291118105, 6596.571222870498, 6597.901031484435, 6615.4376877965915, 6620.0, 5778.8266352009505, 6280.623162610258, 6301.054841773771, 6396.200474940632, 6408.29508754046], "value": 6083.465696428119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.3713328038284, 817.7424111404907, 836.5858899844576, 1058.5765693722506, 1224.173682764134, 34606.48090278642, 38256.61743382565, 39058.804705976465, 40089.398659020066, 40162.7991860041, 32449.83775081127, 40050.39924401129, 40541.0, 40602.0, 40648.98377540563, 7866.606669666522, 8015.599220038975, 8367.790805229888, 8590.570471476432, 8698.826023479527, 8428.53643049632, 8523.786905327386, 8569.414658288237, 8580.828383432328, 8599.312055035582, 8502.107278735737, 8508.702195423162, 8543.487390756574, 8585.484870907734, 8612.741617751477, 8461.280791132762, 8530.616122274512, 8546.829063418729, 8561.22948934599, 8577.099404562556, 8276.131006244317, 8353.665853365857, 8391.496510209407, 8403.957980210122, 8405.621747021398, 36543.0, 36733.63266367329, 36840.0, 36947.4457883132, 36983.815080924694, 3544.8404821783083, 3576.481410195507, 3583.874564390234, 3584.4085725855202, 3587.336342776595, 30656.693433065608, 30717.539236911463, 30729.846350768326, 30733.231669208224, 30849.845750771103, 3118.8284644344553, 3152.984235078833, 3160.383725173592, 3168.9683103168904, 3169.825659588722, 233914.0, 237209.44185837227, 281404.0, 302452.0, 304511.0, 47320.76339618349, 47637.04725905463, 47656.5234347659, 47668.0, 47897.0, 49738.75130624323, 50002.499975000515, 50115.49884501181, 50175.494735158005, 50244.49755502471, 13874.722505550035, 13975.0, 14095.224762637949, 14112.2943852809, 14118.435262589586, 11355.261907975955, 11453.427328633536, 11588.015018723334, 11634.301941883514, 11645.010174135294, 13357.53248636293, 13458.259795711134, 13475.258860762557, 13484.730305394034, 13500.662483437993, 6084.969575152096, 6128.540359472968, 6160.907586386208, 6168.074788781719, 6171.722272497658, 932.8041111366554, 1000.0549480740631, 1006.4816619440847, 1038.5274700011628, 1055.720234137954, 24365.025398984195, 24517.509649806903, 24575.87712061464, 24685.5062898741, 24729.25812225636, 1100.5872797700863, 1103.7626910214342, 1140.4753813245834, 1140.5437824870119, 1140.6635042662435, 12020.355521785194, 12510.999120070372, 12523.874761252453, 12564.308963007123, 12590.481903619313, 320856.0, 322078.0, 322236.0, 322957.0, 323561.0, 53988.730056349465, 54212.72893635585, 54257.0, 54382.72808635931, 54459.18311225335, 1478.4308041404138, 1523.5602355773908, 1529.319452843474, 1531.0737004112575, 1532.0348180646122, 21849.453763656034, 23098.76901230966, 24439.51120977606, 24549.0, 24608.0, 33880.0, 37767.866963991124, 37818.62181378261, 37820.43269351017, 37925.86222413332, 268468.0, 268474.0, 268738.0, 268892.0, 268988.0, 32457.51313730249, 33550.83224583862, 33600.3279934395, 34231.31537369289, 34715.30569388648, 36465.27069458649, 42987.35518967156, 44853.55146448624, 45042.77478612586, 45192.87017824581, 23269.88365058164, 27057.59413608837, 28992.855035724686, 30056.69943300539, 31587.0, 23621.409464763517, 27479.17562473129, 30499.695003049685, 30653.386932261677, 31301.57146420554, 36846.0, 37071.25857482889, 37186.814065929495, 37246.0, 37486.812565937, 28246.870125195583, 29547.52262386915, 29791.851040745525, 30921.0, 31324.686753132177, 23078.653820192376, 23493.53012939766, 24014.0, 24491.877540612182, 24549.140780072612], "value": 17391.627360154842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.3200195687877, 431.2840684463787, 437.6455071392173, 450.31327225980374, 456.16977101674956, 19709.50726231845, 21651.891740541294, 22084.88957555214, 22326.995285212164, 22492.325230243077, 23132.3060308191, 24107.15624953127, 24107.758922410772, 24176.0, 24197.032118715273, 5881.3530511643685, 6147.538934579924, 6176.660283684396, 6184.8453788655415, 6191.380861913818, 5908.438698323653, 5965.701714914258, 6008.218931538894, 6027.789027384042, 6036.909446358308, 5963.075723262901, 5976.252968378953, 5983.311919129313, 5985.670788106652, 6000.3699611540815, 5807.448292412235, 5879.794207202749, 5961.552883533753, 5969.820905372824, 6021.638701677914, 5352.277442545245, 5375.301210842605, 5382.058139825514, 5385.865353366158, 5390.5418039466695, 27240.59139112915, 27690.723092769014, 27795.58306625402, 27829.860850695615, 28706.569401458786, 2169.9023543940402, 2208.8564243324295, 2220.6335954567476, 2229.944251393712, 2232.4084117708658, 18154.455366339032, 18297.908510457495, 18309.084545772792, 18615.53461163468, 18716.532086697804, 2153.881536515491, 2174.3476956912864, 2182.9345119646437, 2209.1494774511766, 2218.267971569378, 151309.0, 157617.0, 159500.20249898793, 159662.99999999884, 159831.0, 12465.314407707663, 12647.177933434481, 12760.6809829755, 12771.489140434465, 12861.356932153354, 16547.83452165487, 16620.836541442226, 16627.667446650998, 16664.00015999044, 16702.164891755605, 8790.95604521986, 8984.101589841095, 9078.182963533314, 9141.177294043568, 9210.677626283048, 8507.744767656981, 8601.01088374845, 8639.395242333103, 8772.956135219283, 8798.12018798115, 6750.561213521106, 8053.234942680436, 8147.429679922467, 8188.426810123234, 8325.000999879932, 2136.5513242219, 2147.3987283560627, 2148.4745830460547, 2152.1499007891794, 2309.815214782813, 941.8728471656239, 966.4153187321709, 967.398954729923, 995.4027583450016, 1008.8486726990864, 21501.67747483789, 21832.0, 21894.233701820678, 22000.899955002187, 22140.55718885613, 855.8587833007608, 892.6875593542209, 999.0758548342753, 1023.9402218703606, 1027.7790275090892, 8708.69519566812, 10821.134309255242, 11163.72090697739, 11184.776304473866, 11222.270552414231, 544146.0, 547362.0, 550346.0, 550904.0, 551674.0, 115197.4240128794, 124964.0, 125868.1119783204, 127804.0, 128947.0, 1078.8651418572679, 1180.7874582575048, 1194.9103817213743, 1242.2174030360816, 1248.4943597842707, 18311.81688183101, 21969.67045494351, 22458.775412245668, 22847.543049139258, 23062.308130756104, 48249.0, 49082.75458622684, 60672.78654426798, 61185.47036324128, 62643.0, 63557.364426356995, 83356.58321708352, 83751.0, 84091.5795421019, 84555.0, 8990.415622984747, 9014.053524379846, 9158.038405967278, 9224.677136300197, 9263.073692630684, 14348.282585870873, 14403.991720579459, 14420.639484012563, 14532.927335363256, 14847.1834049124, 11878.287302761863, 12059.577914773292, 12090.219249572823, 12210.389480526115, 12238.0, 11197.38414387184, 11409.315441073562, 11458.484368203617, 11804.881951180378, 11885.584004560145, 11260.324380537195, 11933.82099268529, 11955.0, 12338.938305308417, 12409.255444673348, 7406.888966655045, 8009.918660980693, 8763.561821908752, 8948.239399650842, 9118.042605526589, 11120.99911008013, 11623.244489108181, 11629.011534019703, 12005.219660722374, 12030.699232519257], "value": 10545.299708327371, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.8536218334891, 1475.0928179169814, 1478.7707905274679, 1481.3556103095154, 1490.1059364381372, 11594.826077608832, 12006.819897701542, 12182.329971851555, 12186.025117990574, 12216.267023978568, 12135.514579416835, 12324.507019719224, 12328.938355308233, 12336.19814712043, 12355.752884942296, 2545.3382120648625, 2574.909878154265, 2593.8832752526087, 2599.454114635929, 2600.804939629526, 2526.2295000024983, 2552.349150966501, 2573.90991315304, 2576.6392705021312, 2578.561644520436, 2158.98920505398, 2538.6192071189375, 2545.5036267927762, 2549.2097449790626, 2550.2349295211457, 2484.8757562122, 2494.3140636324974, 2512.9371765705914, 2519.0, 2520.193538067819, 1998.7901270366692, 2286.6965829477203, 2430.890609922558, 2494.4262819551423, 2507.924762257135, 11876.643700688994, 11979.580714674945, 11982.760344793056, 12003.039756819433, 12027.0, 6088.969555152196, 6302.023186406114, 6755.560888542229, 6767.661616919135, 6788.185417749853, 4035.293823580891, 4202.85290014848, 4298.011457364823, 4304.332828411601, 4304.78476076195, 1193.653840386287, 1203.272020427646, 1230.864604893461, 1240.925544467335, 1261.3504045416619, 15269.160196189205, 15574.688506229812, 16436.75344869828, 16749.91625041879, 16881.155942202964, 5458.972705136449, 5513.5037846594, 5591.832245032656, 5649.887002259932, 5713.085906255063, 11904.166708330507, 11932.164748467523, 11934.820977685342, 11963.46164422603, 11992.520299187934, 6359.2368915729485, 6416.775412860621, 6506.25178104524, 6508.772192973223, 6517.706703198368, 6049.879002420015, 6051.546134039964, 6092.482139018144, 6122.26532816056, 6194.81415557534, 4163.521195062546, 4253.468316460442, 4313.676474264443, 4341.240282950471, 4354.629856462173, 1221.694576355911, 1234.0682784497646, 1246.6820960654975, 1249.1006475337742, 1252.2361359570655, 776.9123227481571, 777.6928113395177, 792.8691765858568, 801.943863929531, 802.2819576479135, 6438.935610643834, 6642.966785166043, 6934.16789985205, 7004.640487001626, 7008.299170083053, 778.7118766056595, 842.6587232170981, 871.9476831390136, 879.7096958003843, 939.910708482693, 3845.2117315950723, 3943.290207762573, 3944.230874979409, 4332.891677708082, 4452.465704115462, 11844.88155118455, 15371.692566148615, 15456.84543154554, 15565.688686226213, 15603.765943510856, 13897.54075822024, 14387.352569134415, 15435.30541125652, 15480.071195728293, 15521.611959701098, 8249.175082491704, 8744.213020828156, 8856.955715221382, 8886.777830554158, 8920.509371984603, 6113.908291375722, 6786.932130678828, 6817.829554261183, 7112.395446387115, 7118.822029449305, 12897.677558061123, 13126.803097953729, 13288.933555332162, 13290.534831280858, 13325.267110309027, 12955.611331660066, 13226.4048117833, 13428.0600357974, 13521.86478135206, 13532.93233533826, 9379.81240375175, 9463.858042129512, 9508.096730810563, 9563.043695630382, 9579.952100239454, 10603.257771955889, 10609.89390106089, 10735.838962415726, 10742.73143171427, 10743.979321964402, 9073.954630226806, 9195.310351723514, 9202.493862837608, 9238.538073096184, 9240.306976976619, 8881.73354799357, 9031.277497800029, 9096.317776166947, 9097.954510227672, 9162.633494660138, 8283.337333013224, 8390.538520381439, 8402.369822263232, 8403.201695838885, 8422.115677853737, 6005.3694362091355, 6017.909731353946, 6087.239095113111, 6174.660393678211, 6228.5951413158, 6040.214772079602, 6077.4834139098675, 6154.692265386802, 6226.501879849692, 6358.9015624844615], "value": 5391.910642177406, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.9258287216865, 404.36514671965006, 404.85829959514166, 408.89777555611096, 409.95080590329167, 6316.810495685131, 6350.523710721691, 6358.601107756298, 6387.201599800025, 6409.711562979672, 4600.802442302601, 5654.858628534278, 6187.319394866561, 6230.971889638205, 6270.65511396873, 3901.7709421532268, 4484.394606728099, 4704.694194877331, 4746.430428348603, 4769.92845107322, 4440.911181776362, 4452.9980754330345, 4707.846577588495, 4778.283257511383, 4786.3059856320815, 4675.158471475133, 4694.765261736899, 4711.528847115295, 4720.03239335937, 4729.4797572267025, 3711.6473934976134, 4198.706090573661, 4664.696794708333, 4697.224957881946, 4734.029523947583, 3814.9152102396633, 4002.679785617144, 4110.0, 4159.9792001039805, 4162.687798415101, 5291.94161167768, 5873.177755114245, 6023.759049638009, 6068.757249710006, 6119.66341851198, 5548.72256387179, 5594.580406469531, 5595.18869763882, 5617.820257745878, 5665.886682266373, 5504.944950550484, 5577.581681373913, 6684.364985326386, 6742.797716068526, 6755.864882702318, 4685.906281874378, 4710.175719249152, 4711.858644240679, 4716.976415117937, 4762.78567464465, 5901.321348044944, 6108.96945515274, 6740.595564266159, 6755.324467553254, 6782.321767823228, 3766.8681596144, 4682.531746825358, 4693.296005599119, 4958.231474121517, 4966.081274964144, 4433.933490997638, 4790.976045119752, 5036.64743467961, 5061.9493805061475, 5064.037832811754, 4854.878628034327, 5641.0, 5672.4894759471845, 5683.147527870769, 5695.487406133468, 5815.360310365856, 6669.833254168588, 6696.560239548423, 6696.732130714814, 6709.798706038729, 1722.6210233748554, 1725.464336740302, 1735.8437740603406, 1737.1314342828587, 1752.9474115776547, 960.6685693435753, 1098.6703988803326, 1173.4543437301695, 1174.4656181437426, 1191.642507247822, 6199.318075011745, 6246.250449946036, 6246.718897649616, 6276.811695649138, 6302.558820882494, 5349.646155691484, 6436.935630643728, 6903.861922761516, 6916.55042422241, 6935.959606059131, 5068.935523540098, 5343.385510666245, 6119.938800612026, 6141.692915354214, 6247.968760156261, 3239.271163988131, 3279.098247981824, 3450.2236996675547, 3621.587580843473, 3650.8904732858055, 5914.34942156371, 6508.8047358579315, 6671.833204169935, 7070.621228860426, 7205.531640443354, 6245.313015568284, 7019.5788252705, 7142.464315176382, 7142.750003749844, 7216.809226477572, 5535.750891209906, 6257.906131408033, 6863.161471162818, 7146.892796608055, 7189.784306470814, 6568.934310656832, 6908.3437073477935, 6975.372216500539, 7031.542949708252, 7092.893606596009, 6642.966785166043, 6860.725570977105, 6863.416609588241, 6903.89644155328, 6908.1019467468905, 7197.568145911262, 7204.71181152748, 7206.603636799817, 7208.387287080657, 7211.3870321023305, 3622.583402908699, 4361.3676016978, 4443.755593442392, 4458.799354029012, 4463.152001119777, 6953.582785032914, 6975.267596902252, 6994.2656021117045, 7008.088948436671, 7246.456515761444, 6185.927714215608, 6247.781327653511, 6456.903146452713, 6507.837304067247, 6778.389944904785, 5907.497862681548, 6048.852657306768, 6215.751369945152, 6701.463882889455, 6704.793137235344, 5697.003024470619, 6419.261784894797, 6558.508111891531, 6573.506986976092, 6580.210374754868, 6066.181065556093, 6361.681915904093, 6683.794347865096, 6694.0, 6730.865382692221, 5122.359705036871, 5512.6692398456225, 6369.617822930639, 6570.178727659043, 6587.80236592903], "value": 4893.469300135488, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.3928901546376, 842.3135144856941, 852.7058164933098, 855.4525103933483, 878.9121087891211, 17032.744508832362, 17285.740713889285, 17449.563760905985, 19419.417417477463, 20163.09266083026, 18538.81461185388, 18685.532861678465, 18740.969246691428, 18795.060246987665, 18821.341253056147, 996.7956568903394, 1011.0849681038671, 1024.3085917006028, 1029.8043371759343, 1030.0317701360734, 1003.2124782046059, 1022.0, 1028.311031608824, 1032.060824649569, 1032.143321043532, 1054.9525271362807, 1083.6207327435413, 1085.3867564825887, 1087.8205096159124, 1090.514720949178, 1064.77639695664, 1082.7563798145393, 1083.4474418046814, 1085.733995171188, 1094.7044298039484, 777.7161336112334, 801.0827602395283, 801.7033697531892, 803.0724513187278, 803.3292201012162, 17456.563585910328, 18008.639827203515, 18168.273269069352, 18217.362392316205, 18314.0, 17273.481795546155, 17712.468625941114, 17775.111244437856, 18149.7277540837, 18149.818501814945, 14368.066075705046, 14703.926480367636, 14884.404623815035, 14887.0, 14907.701845963129, 3453.689167974869, 3533.770304930197, 3534.7525673202886, 3540.0087975366937, 3543.769654972444, 156566.0, 156713.0, 156714.0, 157024.0, 157455.0, 12074.456649450796, 13435.0, 13481.325933703472, 13492.460301588022, 13709.0, 25901.83441745126, 26721.599176012376, 26807.731922680523, 27025.729742702322, 27066.729332706815, 7558.206388329256, 7566.0, 7586.886196707053, 7658.923410765821, 7666.616669166519, 3809.219110082482, 3889.163829776555, 3898.863539776094, 3901.08324543732, 3924.2151569685607, 4381.123775244963, 4399.934000989988, 4453.955460445354, 4460.8661740147845, 4464.2633965396, 866.7876370289193, 881.9857164261151, 895.1227796759213, 896.4218079338921, 911.6763548940031, 643.240975648739, 658.5127006015609, 665.2084020016165, 671.660811290305, 681.4752640466813, 9462.90537094634, 9946.35348702332, 11848.703782405337, 11905.107116966263, 12371.876281237252, 636.4113195294334, 639.9936000640027, 649.7920665387031, 652.7650045983443, 668.3583759590847, 4950.158473059589, 5585.273914391184, 5774.364819869811, 5779.335376431764, 5792.478676919098, 145310.27344863207, 145740.0, 145845.0, 148079.25960370345, 149585.0, 124805.37597311955, 125577.0, 126797.0, 129353.0, 130419.0, 83901.0, 108147.99999999843, 108614.0, 110054.0, 111766.0, 11196.496157673088, 11434.542618295507, 11787.292762434281, 12484.937575312066, 12653.493860245488, 36400.0, 39329.016774580865, 41448.0, 42972.0, 43560.0, 68844.6557767208, 71622.0, 83966.16033839827, 84201.0, 85114.0, 16942.915285423493, 17371.826281737525, 17608.911955440653, 17712.734308985633, 17750.556236094202, 18773.249070037593, 18868.43394698161, 18953.14710837988, 18990.43028709141, 19059.237630494627, 17915.283388664306, 17943.0, 18044.90977545104, 18080.81919180844, 18230.908845455688, 17222.655546889244, 17810.91094544519, 17849.286028558712, 18165.636687266444, 18284.268629255213, 14981.925090374914, 16760.832391675925, 17543.035133067293, 17690.823091768918, 17851.46445606634, 11671.824922625998, 11700.648980530597, 12080.0, 12207.938960305142, 12289.324087175291, 12066.517339306329, 13439.0, 13559.728805424034, 13566.050376473551, 13628.454861805416], "value": 8940.109487696816, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1508.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 167.0, 128.0, 115.0, 116.0, 115.0, 115.0, 116.0, 114.0, 115.0], "value": 120.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 119.0, 173.0, 143.0, 140.0, 112.0, 107.0, 135.0, 110.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 201.0, 197.0, 235.0, 259.0, 244.0, 199.0, 195.0, 198.0, 196.0], "value": 211.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 82.0, 58.0, 56.0, 61.0, 60.0, 74.0, 60.0], "value": 64.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 82.0, 71.0, 68.0, 69.0, 90.0, 70.0, 70.0, 102.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 88.0, 90.0, 88.0, 90.0, 89.0, 88.0, 95.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 107.0, 110.0, 97.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 110.0, 105.0, 93.0, 89.0, 90.0, 90.0, 122.0, 89.0, 95.0], "value": 97.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 47.0, 49.0, 53.0, 51.0, 52.0, 49.0, 48.0, 48.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 58.0, 60.0, 66.0, 59.0, 54.0, 57.0, 54.0, 55.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 85.0, 84.0, 88.0, 87.0, 86.0, 139.0, 116.0], "value": 94.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 170.0, 148.0, 134.0, 136.0, 137.0, 135.0, 139.0, 152.0, 141.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 171.0, 236.0, 231.0, 217.0, 234.0, 226.0, 191.0, 186.0], "value": 206.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 68.0, 68.0, 65.0, 67.0, 68.0, 67.0, 69.0, 68.0], "value": 67.3, "unit": "score"}]}]} 07:53:11 INFO - Return code: 0 07:53:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:53:11 INFO - # TBPL SUCCESS # 07:53:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:53:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:53:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:53:11 INFO - 2017-05-25 07:53:11.160 screenresolution[1666:11200] starting screenresolution argv=screenresolution get 07:53:11 INFO - 2017-05-25 07:53:11.163 screenresolution[1666:11200] Display 0: 1600x1200x32@60 07:53:11 INFO - 2017-05-25 07:53:11.169 screenresolution[1667:11204] starting screenresolution argv=screenresolution list 07:53:11 INFO - Available Modes on Display 0 07:53:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:53:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:53:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:53:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:53:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:53:11 INFO - Intel Iris: 07:53:11 INFO - Chipset Model: Intel Iris 07:53:11 INFO - Type: GPU 07:53:11 INFO - Bus: Built-In 07:53:11 INFO - VRAM (Dynamic, Max): 1536 MB 07:53:11 INFO - Vendor: Intel (0x8086) 07:53:11 INFO - Device ID: 0x0a2e 07:53:11 INFO - Revision ID: 0x0009 07:53:11 INFO - Displays: 07:53:11 INFO - Display: 07:53:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:53:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:53:11 INFO - Display Serial Number: 1600x1200 60 07:53:11 INFO - Main Display: Yes 07:53:11 INFO - Mirror: Off 07:53:11 INFO - Online: Yes 07:53:11 INFO - Rotation: Supported 07:53:11 INFO - Return code: 0 07:53:11 INFO - Running post-action listener: _resource_record_post_action 07:53:11 INFO - [mozharness: 2017-05-25 14:53:11.402050Z] Finished run-tests step (success) 07:53:11 INFO - Running post-run listener: _resource_record_post_run 07:53:11 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 42093568; Write bytes: 563237376; Read time: 292; Write time: 1748 07:53:11 INFO - TinderboxPrint: CPU usage
53.4% 07:53:11 INFO - TinderboxPrint: I/O read bytes / time
42,093,568 / 292 07:53:11 INFO - TinderboxPrint: I/O write bytes / time
563,237,376 / 1,748 07:53:11 INFO - TinderboxPrint: CPU idle
2,341.4 (46.6%) 07:53:11 INFO - TinderboxPrint: CPU system
1,016.8 (20.2%) 07:53:11 INFO - TinderboxPrint: CPU user
1,663.5 (33.1%) 07:53:11 INFO - TinderboxPrint: Swap in / out
284,151,808 / 0 07:53:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 235151872; Write bytes: 227520512; Read time: 16878; Write time: 481 07:53:11 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 40209408; Write bytes: 317456896; Read time: 275; Write time: 1215 07:53:11 INFO - Running post-run listener: _upload_blobber_files 07:53:11 INFO - Blob upload gear active. 07:53:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:53:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:53:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:53:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:53:12 INFO - (blobuploader) - INFO - Open directory for files ... 07:53:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:53:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:13 INFO - (blobuploader) - INFO - Done attempting. 07:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:53:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:14 INFO - (blobuploader) - INFO - Done attempting. 07:53:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:53:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:53:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:15 INFO - (blobuploader) - INFO - Done attempting. 07:53:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:53:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:53:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:53:17 INFO - (blobuploader) - INFO - Done attempting. 07:53:17 INFO - (blobuploader) - INFO - Iteration through files over. 07:53:17 INFO - Return code: 0 07:53:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:53:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:53:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c33ea8f333186e0f86a00b14173dbef21d704469afa439651188550221ab10f965fe64a3069065343c12a6604fda2d7211b4c615b6ae33515c9dea58360592b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd0e015be152fb40aea87c316fab7c8c54f5e0477fa94221c837d7aac7173b64285e08b986eca7c3f97b74a9b91cc3066f7f45e6f1b193fedd5cd58919b240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107a8676def0cf064f4b0ce963a508d510a93e5b8e399e92acf948f4b7b8ba29a22a69aa85f8e39dcd1ade51cdc021d11ce5364460312a327cd05587bca40b8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f79cde877d6b84f3d291918e65613b27e2e29201681d4f819538e4cc52afee8cde91cab6c5c8bfdbaec3b76be329ddf56157803cb4d2c5b848ae4545d90fb3b7"} 07:53:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:53:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:53:17 INFO - Contents: 07:53:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c33ea8f333186e0f86a00b14173dbef21d704469afa439651188550221ab10f965fe64a3069065343c12a6604fda2d7211b4c615b6ae33515c9dea58360592b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd0e015be152fb40aea87c316fab7c8c54f5e0477fa94221c837d7aac7173b64285e08b986eca7c3f97b74a9b91cc3066f7f45e6f1b193fedd5cd58919b240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107a8676def0cf064f4b0ce963a508d510a93e5b8e399e92acf948f4b7b8ba29a22a69aa85f8e39dcd1ade51cdc021d11ce5364460312a327cd05587bca40b8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f79cde877d6b84f3d291918e65613b27e2e29201681d4f819538e4cc52afee8cde91cab6c5c8bfdbaec3b76be329ddf56157803cb4d2c5b848ae4545d90fb3b7"} 07:53:17 INFO - Running post-run listener: copy_logs_to_upload_dir 07:53:17 INFO - Copying logs to upload dir... 07:53:17 INFO - mkdir: /builds/slave/test/build/upload/logs 07:53:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.514971 ========= master_lag: 2.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-25 07:53:19.625119) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 07:53:19.628852) ========= 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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c33ea8f333186e0f86a00b14173dbef21d704469afa439651188550221ab10f965fe64a3069065343c12a6604fda2d7211b4c615b6ae33515c9dea58360592b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd0e015be152fb40aea87c316fab7c8c54f5e0477fa94221c837d7aac7173b64285e08b986eca7c3f97b74a9b91cc3066f7f45e6f1b193fedd5cd58919b240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107a8676def0cf064f4b0ce963a508d510a93e5b8e399e92acf948f4b7b8ba29a22a69aa85f8e39dcd1ade51cdc021d11ce5364460312a327cd05587bca40b8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f79cde877d6b84f3d291918e65613b27e2e29201681d4f819538e4cc52afee8cde91cab6c5c8bfdbaec3b76be329ddf56157803cb4d2c5b848ae4545d90fb3b7"} build_url:https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/MWsVQ6ORSsiRfwW2Dfj5ZQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c33ea8f333186e0f86a00b14173dbef21d704469afa439651188550221ab10f965fe64a3069065343c12a6604fda2d7211b4c615b6ae33515c9dea58360592b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfd0e015be152fb40aea87c316fab7c8c54f5e0477fa94221c837d7aac7173b64285e08b986eca7c3f97b74a9b91cc3066f7f45e6f1b193fedd5cd58919b240", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/107a8676def0cf064f4b0ce963a508d510a93e5b8e399e92acf948f4b7b8ba29a22a69aa85f8e39dcd1ade51cdc021d11ce5364460312a327cd05587bca40b8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f79cde877d6b84f3d291918e65613b27e2e29201681d4f819538e4cc52afee8cde91cab6c5c8bfdbaec3b76be329ddf56157803cb4d2c5b848ae4545d90fb3b7"}' ========= master_lag: 0.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 07:53:19.822141) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 07:53:19.822477) ========= 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.Wgu5uJzJGX/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.CXlpmJdB43/Listeners TMPDIR=/var/folders/xs/dz060mwx3k394rmwvy72m0zr00000w/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.004988 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 07:53:19.848225) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 07:53:19.848555) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 07:53:19.848873) ========= ========= Total master_lag: 2.34 =========