builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1470888317.64 results: success (0) buildid: 20160810193025 builduid: 0298aea4ee644bae993d4672c1ecb40a revision: 0502bd9e025edde29777ba1de4280f9b52af4663 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.640831) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.641268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.641562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.656529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.656864) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1470888079.739826-211675254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-10 21:05:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.9M=0s 2016-08-10 21:05:17 (69.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076227 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.753588) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.753909) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1470888079.739826-211675254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017937 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.808300) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:17.808681) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0502bd9e025edde29777ba1de4280f9b52af4663 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0502bd9e025edde29777ba1de4280f9b52af4663 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1470888079.739826-211675254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-10 21:05:17,938 truncating revision to first 12 chars 2016-08-10 21:05:17,938 Setting DEBUG logging. 2016-08-10 21:05:17,938 attempt 1/10 2016-08-10 21:05:17,938 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0502bd9e025e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-10 21:05:18,237 unpacking tar archive at: mozilla-central-0502bd9e025e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630699 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:18.455397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:18.455714) ========= script_repo_revision: 0502bd9e025edde29777ba1de4280f9b52af4663 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:18.456086) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:18.456357) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-10 21:05:18.467295) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-10 21:05:18.467673) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_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', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1470888079.739826-211675254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:05:18 INFO - MultiFileLogger online at 20160810 21:05:18 in /builds/slave/test 21:05:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:05:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:05:18 INFO - {'append_to_log': False, 21:05:18 INFO - 'base_work_dir': '/builds/slave/test', 21:05:18 INFO - 'blob_upload_branch': 'Firefox', 21:05:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:05:18 INFO - 'branch': 'Firefox', 21:05:18 INFO - 'buildbot_json_path': 'buildprops.json', 21:05:18 INFO - 'config_files': ('talos/linux_config.py',), 21:05:18 INFO - 'default_actions': ('clobber', 21:05:18 INFO - 'read-buildbot-config', 21:05:18 INFO - 'download-and-extract', 21:05:18 INFO - 'populate-webroot', 21:05:18 INFO - 'create-virtualenv', 21:05:18 INFO - 'install', 21:05:18 INFO - 'run-tests'), 21:05:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:05:18 INFO - 'download_minidump_stackwalk': True, 21:05:18 INFO - 'download_symbols': 'ondemand', 21:05:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:05:18 INFO - 'tooltool.py': '/tools/tooltool.py', 21:05:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:05:18 INFO - '/tools/misc-python/virtualenv.py')}, 21:05:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:05:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:05:18 INFO - 'installer_path': 'installer.exe', 21:05:18 INFO - 'log_level': 'info', 21:05:18 INFO - 'log_name': 'talos', 21:05:18 INFO - 'log_to_console': True, 21:05:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:05:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:05:18 INFO - 'opt_config_files': (), 21:05:18 INFO - 'pip_index': False, 21:05:18 INFO - 'sps_profile': False, 21:05:18 INFO - 'sps_profile_interval': 0, 21:05:18 INFO - 'suite': 'dromaeojs', 21:05:18 INFO - 'system_bits': '32', 21:05:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:05:18 INFO - 'title': 'talos-linux64-ix-007', 21:05:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:05:18 INFO - 'use_talos_json': True, 21:05:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:05:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:05:18 INFO - 'work_dir': 'build'} 21:05:18 INFO - [mozharness: 2016-08-11 04:05:18.685776Z] Running clobber step. 21:05:18 INFO - Running pre-action listener: _resource_record_pre_action 21:05:18 INFO - Running main action method: clobber 21:05:18 INFO - rmtree: /builds/slave/test/build 21:05:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:05:19 INFO - Running post-action listener: _resource_record_post_action 21:05:19 INFO - [mozharness: 2016-08-11 04:05:19.140704Z] Finished clobber step (success) 21:05:19 INFO - [mozharness: 2016-08-11 04:05:19.140805Z] Running read-buildbot-config step. 21:05:19 INFO - Running pre-action listener: _resource_record_pre_action 21:05:19 INFO - Running main action method: read_buildbot_config 21:05:19 INFO - Using buildbot properties: 21:05:19 INFO - { 21:05:19 INFO - "project": "", 21:05:19 INFO - "product": "firefox", 21:05:19 INFO - "script_repo_revision": "production", 21:05:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:05:19 INFO - "repository": "", 21:05:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:05:19 INFO - "buildid": "20160810193025", 21:05:19 INFO - "pgo_build": "True", 21:05:19 INFO - "basedir": "/builds/slave/test", 21:05:19 INFO - "buildnumber": 828, 21:05:19 INFO - "slavename": "talos-linux64-ix-007", 21:05:19 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:05:19 INFO - "platform": "ubuntu64_hw", 21:05:19 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:05:19 INFO - "branch": "mozilla-central", 21:05:19 INFO - "repo_path": "mozilla-central", 21:05:19 INFO - "revision": "0502bd9e025edde29777ba1de4280f9b52af4663", 21:05:19 INFO - "stage_platform": "linux64-pgo", 21:05:19 INFO - "builduid": "0298aea4ee644bae993d4672c1ecb40a", 21:05:19 INFO - "slavebuilddir": "test" 21:05:19 INFO - } 21:05:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 21:05:19 INFO - Running post-action listener: _resource_record_post_action 21:05:19 INFO - [mozharness: 2016-08-11 04:05:19.143251Z] Finished read-buildbot-config step (success) 21:05:19 INFO - [mozharness: 2016-08-11 04:05:19.143341Z] Running download-and-extract step. 21:05:19 INFO - Running pre-action listener: _resource_record_pre_action 21:05:19 INFO - Running main action method: download_and_extract 21:05:19 INFO - mkdir: /builds/slave/test/build/tests 21:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:19 INFO - trying https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:05:19 INFO - Downloading https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:05:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:05:19 INFO - Downloaded 1361 bytes. 21:05:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 21:05:19 INFO - Using the following test package requirements: 21:05:19 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:05:19 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:05:19 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:05:19 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'jsshell-linux-x86_64.zip'], 21:05:19 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:05:19 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 21:05:19 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:05:19 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:05:19 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:05:19 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 21:05:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:05:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:19 INFO - trying https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:05:19 INFO - Downloading https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:05:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:05:20 INFO - Downloaded 21833795 bytes. 21:05:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:05:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:05:22 INFO - Return code: 0 21:05:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:22 INFO - trying https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:05:22 INFO - Downloading https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 21:05:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:05:23 INFO - Downloaded 21833795 bytes. 21:05:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:05:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:05:24 INFO - Return code: 0 21:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:24 INFO - trying https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 21:05:24 INFO - Downloading https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 21:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:05:25 INFO - Downloaded 14413719 bytes. 21:05:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:05:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:05:25 INFO - Return code: 0 21:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:25 INFO - trying https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:05:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:05:26 INFO - Downloaded 59332700 bytes. 21:05:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:05:26 INFO - mkdir: /builds/slave/test/properties 21:05:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:05:26 INFO - Writing to file /builds/slave/test/properties/build_url 21:05:26 INFO - Contents: 21:05:26 INFO - build_url:https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 21:05:27 INFO - Running post-action listener: _resource_record_post_action 21:05:27 INFO - Running post-action listener: set_extra_try_arguments 21:05:27 INFO - [mozharness: 2016-08-11 04:05:27.493879Z] Finished download-and-extract step (success) 21:05:27 INFO - [mozharness: 2016-08-11 04:05:27.494110Z] Running populate-webroot step. 21:05:27 INFO - Running pre-action listener: _resource_record_pre_action 21:05:27 INFO - Running main action method: populate_webroot 21:05:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:05:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:05:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:05:27 INFO - '--tppagecycles', 21:05:27 INFO - '7'], 21:05:27 INFO - 'tests': ['tsvgm']}}, 21:05:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:05:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:05:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:05:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:05:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:05:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:05:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['damp', 'tps']}, 21:05:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'tests': ['damp', 'tps']}, 21:05:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['dromaeo_dom']}, 21:05:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:05:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['basic_compositor_video']}, 21:05:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:05:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['a11yr', 21:05:27 INFO - 'ts_paint', 21:05:27 INFO - 'tpaint', 21:05:27 INFO - 'sessionrestore', 21:05:27 INFO - 'sessionrestore_no_auto_restore', 21:05:27 INFO - 'tabpaint']}, 21:05:27 INFO - 'other-e10s': {'tests': ['a11yr', 21:05:27 INFO - 'ts_paint', 21:05:27 INFO - 'tpaint', 21:05:27 INFO - 'sessionrestore', 21:05:27 INFO - 'sessionrestore_no_auto_restore', 21:05:27 INFO - 'tabpaint']}, 21:05:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:05:27 INFO - 'ts_paint', 21:05:27 INFO - 'tpaint', 21:05:27 INFO - 'sessionrestore', 21:05:27 INFO - 'sessionrestore_no_auto_restore', 21:05:27 INFO - 'tabpaint']}, 21:05:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:05:27 INFO - 'ts_paint', 21:05:27 INFO - 'tpaint', 21:05:27 INFO - 'sessionrestore', 21:05:27 INFO - 'sessionrestore_no_auto_restore', 21:05:27 INFO - 'tabpaint']}, 21:05:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['a11yr', 21:05:27 INFO - 'ts_paint', 21:05:27 INFO - 'tpaint', 21:05:27 INFO - 'sessionrestore', 21:05:27 INFO - 'sessionrestore_no_auto_restore', 21:05:27 INFO - 'tabpaint']}, 21:05:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['a11yr', 21:05:27 INFO - 'ts_paint', 21:05:27 INFO - 'tpaint', 21:05:27 INFO - 'sessionrestore', 21:05:27 INFO - 'sessionrestore_no_auto_restore', 21:05:27 INFO - 'tabpaint']}, 21:05:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['tsvgx', 21:05:27 INFO - 'tsvgr_opacity', 21:05:27 INFO - 'tart', 21:05:27 INFO - 'tscrollx', 21:05:27 INFO - 'cart']}, 21:05:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:05:27 INFO - 'tsvgr_opacity', 21:05:27 INFO - 'tart', 21:05:27 INFO - 'tscrollx', 21:05:27 INFO - 'cart']}, 21:05:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'talos_options': ['--disable-e10s'], 21:05:27 INFO - 'tests': ['tp5o']}, 21:05:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'tests': ['tp5o']}, 21:05:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'talos_options': ['--disable-e10s', 21:05:27 INFO - '--xperf_path', 21:05:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:05:27 INFO - 'tests': ['tp5n']}, 21:05:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:05:27 INFO - 'talos_options': ['--xperf_path', 21:05:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:05:27 INFO - 'tests': ['tp5n']}}, 21:05:27 INFO - 'talos.zip': {'path': '', 21:05:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:05:27 INFO - Running post-action listener: _resource_record_post_action 21:05:27 INFO - [mozharness: 2016-08-11 04:05:27.514375Z] Finished populate-webroot step (success) 21:05:27 INFO - [mozharness: 2016-08-11 04:05:27.514464Z] Running create-virtualenv step. 21:05:27 INFO - Running pre-action listener: _resource_record_pre_action 21:05:27 INFO - Running main action method: create_virtualenv 21:05:27 INFO - Creating virtualenv /builds/slave/test/build/venv 21:05:27 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 21:05:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:05:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:05:27 INFO - Using real prefix '/usr' 21:05:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:05:28 INFO - Installing distribute.............................................................................................................................................................................................done. 21:05:30 INFO - Installing pip.................done. 21:05:30 INFO - Return code: 0 21:05:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:05:30 INFO - Using env: {'DISPLAY': ':0', 21:05:30 INFO - 'HOME': '/home/cltbld', 21:05:30 INFO - 'LANG': 'en_US.UTF-8', 21:05:30 INFO - 'LANGUAGE': 'en_US:en', 21:05:30 INFO - 'LOGNAME': 'cltbld', 21:05:30 INFO - 'MAIL': '/var/mail/cltbld', 21:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:30 INFO - 'MOZ_NO_REMOTE': '1', 21:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:30 INFO - 'NO_EM_RESTART': '1', 21:05:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:30 INFO - 'PWD': '/builds/slave/test', 21:05:30 INFO - 'SHELL': '/bin/bash', 21:05:30 INFO - 'SHLVL': '1', 21:05:30 INFO - 'TERM': 'linux', 21:05:30 INFO - 'TMOUT': '86400', 21:05:30 INFO - 'USER': 'cltbld', 21:05:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:30 INFO - '_': '/tools/buildbot/bin/python'} 21:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:30 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 21:05:30 INFO - Cleaning up... 21:05:30 INFO - Return code: 0 21:05:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:30 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:05:30 INFO - Using env: {'DISPLAY': ':0', 21:05:30 INFO - 'HOME': '/home/cltbld', 21:05:30 INFO - 'LANG': 'en_US.UTF-8', 21:05:30 INFO - 'LANGUAGE': 'en_US:en', 21:05:30 INFO - 'LOGNAME': 'cltbld', 21:05:30 INFO - 'MAIL': '/var/mail/cltbld', 21:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:30 INFO - 'MOZ_NO_REMOTE': '1', 21:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:30 INFO - 'NO_EM_RESTART': '1', 21:05:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:30 INFO - 'PWD': '/builds/slave/test', 21:05:30 INFO - 'SHELL': '/bin/bash', 21:05:30 INFO - 'SHLVL': '1', 21:05:30 INFO - 'TERM': 'linux', 21:05:30 INFO - 'TMOUT': '86400', 21:05:30 INFO - 'USER': 'cltbld', 21:05:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:30 INFO - '_': '/tools/buildbot/bin/python'} 21:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:30 INFO - Downloading/unpacking psutil>=3.1.1 21:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:05:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:05:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:05:32 INFO - Installing collected packages: psutil 21:05:32 INFO - Running setup.py install for psutil 21:05:32 INFO - building 'psutil._psutil_linux' extension 21:05:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:05:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:05:32 INFO - building 'psutil._psutil_posix' extension 21:05:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:05:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:05:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:05:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:05:33 INFO - Successfully installed psutil 21:05:33 INFO - Cleaning up... 21:05:33 INFO - Return code: 0 21:05:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:05:33 INFO - Using env: {'DISPLAY': ':0', 21:05:33 INFO - 'HOME': '/home/cltbld', 21:05:33 INFO - 'LANG': 'en_US.UTF-8', 21:05:33 INFO - 'LANGUAGE': 'en_US:en', 21:05:33 INFO - 'LOGNAME': 'cltbld', 21:05:33 INFO - 'MAIL': '/var/mail/cltbld', 21:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:33 INFO - 'MOZ_NO_REMOTE': '1', 21:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:33 INFO - 'NO_EM_RESTART': '1', 21:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:33 INFO - 'PWD': '/builds/slave/test', 21:05:33 INFO - 'SHELL': '/bin/bash', 21:05:33 INFO - 'SHLVL': '1', 21:05:33 INFO - 'TERM': 'linux', 21:05:33 INFO - 'TMOUT': '86400', 21:05:33 INFO - 'USER': 'cltbld', 21:05:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:33 INFO - '_': '/tools/buildbot/bin/python'} 21:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:05:33 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 21:05:33 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 21:05:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:33 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 21:05:33 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 21:05:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:05:35 INFO - Installing collected packages: mozsystemmonitor 21:05:35 INFO - Running setup.py install for mozsystemmonitor 21:05:35 INFO - Successfully installed mozsystemmonitor 21:05:35 INFO - Cleaning up... 21:05:35 INFO - Return code: 0 21:05:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:35 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:05:35 INFO - Using env: {'DISPLAY': ':0', 21:05:35 INFO - 'HOME': '/home/cltbld', 21:05:35 INFO - 'LANG': 'en_US.UTF-8', 21:05:35 INFO - 'LANGUAGE': 'en_US:en', 21:05:35 INFO - 'LOGNAME': 'cltbld', 21:05:35 INFO - 'MAIL': '/var/mail/cltbld', 21:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:35 INFO - 'MOZ_NO_REMOTE': '1', 21:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:35 INFO - 'NO_EM_RESTART': '1', 21:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:35 INFO - 'PWD': '/builds/slave/test', 21:05:35 INFO - 'SHELL': '/bin/bash', 21:05:35 INFO - 'SHLVL': '1', 21:05:35 INFO - 'TERM': 'linux', 21:05:35 INFO - 'TMOUT': '86400', 21:05:35 INFO - 'USER': 'cltbld', 21:05:35 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:35 INFO - '_': '/tools/buildbot/bin/python'} 21:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:35 INFO - Downloading/unpacking jsonschema==2.5.1 21:05:35 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 21:05:35 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 21:05:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:35 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 21:05:35 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 21:05:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:05:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:05:37 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 21:05:37 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 21:05:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:37 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 21:05:37 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 21:05:37 INFO - Downloading functools32-3.2.3-2.tar.gz 21:05:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:05:37 INFO - warning: no files found matching '*.txt' 21:05:37 INFO - no previously-included directories found matching 'build' 21:05:37 INFO - no previously-included directories found matching 'dist' 21:05:37 INFO - no previously-included directories found matching '.git*' 21:05:37 INFO - Installing collected packages: jsonschema, functools32 21:05:37 INFO - Running setup.py install for functools32 21:05:38 INFO - warning: no files found matching '*.txt' 21:05:38 INFO - no previously-included directories found matching 'build' 21:05:38 INFO - no previously-included directories found matching 'dist' 21:05:38 INFO - no previously-included directories found matching '.git*' 21:05:38 INFO - Successfully installed jsonschema functools32 21:05:38 INFO - Cleaning up... 21:05:38 INFO - Return code: 0 21:05:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:05:38 INFO - Using env: {'DISPLAY': ':0', 21:05:38 INFO - 'HOME': '/home/cltbld', 21:05:38 INFO - 'LANG': 'en_US.UTF-8', 21:05:38 INFO - 'LANGUAGE': 'en_US:en', 21:05:38 INFO - 'LOGNAME': 'cltbld', 21:05:38 INFO - 'MAIL': '/var/mail/cltbld', 21:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:38 INFO - 'MOZ_NO_REMOTE': '1', 21:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:38 INFO - 'NO_EM_RESTART': '1', 21:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:38 INFO - 'PWD': '/builds/slave/test', 21:05:38 INFO - 'SHELL': '/bin/bash', 21:05:38 INFO - 'SHLVL': '1', 21:05:38 INFO - 'TERM': 'linux', 21:05:38 INFO - 'TMOUT': '86400', 21:05:38 INFO - 'USER': 'cltbld', 21:05:38 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:38 INFO - '_': '/tools/buildbot/bin/python'} 21:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:05:38 INFO - Cleaning up... 21:05:38 INFO - Return code: 0 21:05:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:05:38 INFO - Using env: {'DISPLAY': ':0', 21:05:38 INFO - 'HOME': '/home/cltbld', 21:05:38 INFO - 'LANG': 'en_US.UTF-8', 21:05:38 INFO - 'LANGUAGE': 'en_US:en', 21:05:38 INFO - 'LOGNAME': 'cltbld', 21:05:38 INFO - 'MAIL': '/var/mail/cltbld', 21:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:38 INFO - 'MOZ_NO_REMOTE': '1', 21:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:38 INFO - 'NO_EM_RESTART': '1', 21:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:38 INFO - 'PWD': '/builds/slave/test', 21:05:38 INFO - 'SHELL': '/bin/bash', 21:05:38 INFO - 'SHLVL': '1', 21:05:38 INFO - 'TERM': 'linux', 21:05:38 INFO - 'TMOUT': '86400', 21:05:38 INFO - 'USER': 'cltbld', 21:05:38 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:38 INFO - '_': '/tools/buildbot/bin/python'} 21:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:38 INFO - Downloading/unpacking blobuploader==1.2.4 21:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:40 INFO - Downloading blobuploader-1.2.4.tar.gz 21:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:05:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:05:40 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 21:05:40 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 21:05:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:40 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 21:05:40 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 21:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:05:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:05:40 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 21:05:40 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 21:05:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:40 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 21:05:40 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 21:05:41 INFO - Downloading docopt-0.6.1.tar.gz 21:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:05:41 INFO - Installing collected packages: blobuploader, requests, docopt 21:05:41 INFO - Running setup.py install for blobuploader 21:05:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:05:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:05:41 INFO - Running setup.py install for requests 21:05:41 INFO - Running setup.py install for docopt 21:05:41 INFO - Successfully installed blobuploader requests docopt 21:05:41 INFO - Cleaning up... 21:05:41 INFO - Return code: 0 21:05:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:05:41 INFO - Using env: {'DISPLAY': ':0', 21:05:41 INFO - 'HOME': '/home/cltbld', 21:05:41 INFO - 'LANG': 'en_US.UTF-8', 21:05:41 INFO - 'LANGUAGE': 'en_US:en', 21:05:41 INFO - 'LOGNAME': 'cltbld', 21:05:41 INFO - 'MAIL': '/var/mail/cltbld', 21:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:41 INFO - 'MOZ_NO_REMOTE': '1', 21:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:41 INFO - 'NO_EM_RESTART': '1', 21:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:41 INFO - 'PWD': '/builds/slave/test', 21:05:41 INFO - 'SHELL': '/bin/bash', 21:05:41 INFO - 'SHLVL': '1', 21:05:41 INFO - 'TERM': 'linux', 21:05:41 INFO - 'TMOUT': '86400', 21:05:41 INFO - 'USER': 'cltbld', 21:05:41 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:41 INFO - '_': '/tools/buildbot/bin/python'} 21:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:05:42 INFO - Running setup.py (path:/tmp/pip-HW_Sws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:05:42 INFO - Running setup.py (path:/tmp/pip-EDpQqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:05:42 INFO - Running setup.py (path:/tmp/pip-0Uotwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:05:42 INFO - Running setup.py (path:/tmp/pip-jYbb50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:05:42 INFO - Running setup.py (path:/tmp/pip-gzQpdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:05:42 INFO - Running setup.py (path:/tmp/pip-3BKzar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:05:42 INFO - Running setup.py (path:/tmp/pip-zUhGyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:05:42 INFO - Running setup.py (path:/tmp/pip-YKWAQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:05:42 INFO - Running setup.py (path:/tmp/pip-PukRha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:05:42 INFO - Running setup.py (path:/tmp/pip-5JwLk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:05:42 INFO - Running setup.py (path:/tmp/pip-avXOWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:05:42 INFO - Running setup.py (path:/tmp/pip-DXcYfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:05:42 INFO - Running setup.py (path:/tmp/pip-fVhKaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:05:43 INFO - Running setup.py (path:/tmp/pip-rfLJbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:43 INFO - Running setup.py (path:/tmp/pip-b9jvpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:05:43 INFO - Running setup.py (path:/tmp/pip-r_eRa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:05:43 INFO - Running setup.py (path:/tmp/pip-RusfEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:05:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:05:43 INFO - Running setup.py install for manifestparser 21:05:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:05:43 INFO - Running setup.py install for mozcrash 21:05:43 INFO - Running setup.py install for mozdebug 21:05:43 INFO - Running setup.py install for mozdevice 21:05:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:05:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:05:43 INFO - Running setup.py install for mozfile 21:05:43 INFO - Running setup.py install for mozhttpd 21:05:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:05:43 INFO - Running setup.py install for mozinfo 21:05:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Running setup.py install for mozInstall 21:05:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Running setup.py install for mozleak 21:05:44 INFO - Running setup.py install for mozlog 21:05:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Running setup.py install for moznetwork 21:05:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Running setup.py install for mozprocess 21:05:44 INFO - Running setup.py install for mozprofile 21:05:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Running setup.py install for mozrunner 21:05:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:05:44 INFO - Running setup.py install for mozscreenshot 21:05:44 INFO - Running setup.py install for moztest 21:05:44 INFO - Running setup.py install for mozversion 21:05:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:05:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:05:45 INFO - Cleaning up... 21:05:45 INFO - Return code: 0 21:05:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:45 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:05:45 INFO - Using env: {'DISPLAY': ':0', 21:05:45 INFO - 'HOME': '/home/cltbld', 21:05:45 INFO - 'LANG': 'en_US.UTF-8', 21:05:45 INFO - 'LANGUAGE': 'en_US:en', 21:05:45 INFO - 'LOGNAME': 'cltbld', 21:05:45 INFO - 'MAIL': '/var/mail/cltbld', 21:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:45 INFO - 'MOZ_NO_REMOTE': '1', 21:05:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:45 INFO - 'NO_EM_RESTART': '1', 21:05:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:45 INFO - 'PWD': '/builds/slave/test', 21:05:45 INFO - 'SHELL': '/bin/bash', 21:05:45 INFO - 'SHLVL': '1', 21:05:45 INFO - 'TERM': 'linux', 21:05:45 INFO - 'TMOUT': '86400', 21:05:45 INFO - 'USER': 'cltbld', 21:05:45 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:45 INFO - '_': '/tools/buildbot/bin/python'} 21:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:05:45 INFO - Running setup.py (path:/tmp/pip-wUjM89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:05:45 INFO - Running setup.py (path:/tmp/pip-h48ZGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:05:45 INFO - Running setup.py (path:/tmp/pip-ce8V53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:05:45 INFO - Running setup.py (path:/tmp/pip-Q_alKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:05:45 INFO - Running setup.py (path:/tmp/pip-OmhdyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:05:45 INFO - Running setup.py (path:/tmp/pip-eeMs8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:05:45 INFO - Running setup.py (path:/tmp/pip-07ZnY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:05:45 INFO - Running setup.py (path:/tmp/pip-wcer76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:05:45 INFO - Running setup.py (path:/tmp/pip-vyL0K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:05:45 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)) 21:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:05:45 INFO - Running setup.py (path:/tmp/pip-yBpiA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:05:46 INFO - Running setup.py (path:/tmp/pip-Jj8xVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:05:46 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:05:46 INFO - Running setup.py (path:/tmp/pip-405u8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:05:46 INFO - Running setup.py (path:/tmp/pip-XuoWfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:05:46 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:05:46 INFO - Running setup.py (path:/tmp/pip-mhHKST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:46 INFO - Running setup.py (path:/tmp/pip-gn33WW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:46 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:05:46 INFO - Running setup.py (path:/tmp/pip-XbSxTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 21:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:05:46 INFO - Running setup.py (path:/tmp/pip-r5USs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:05:46 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)) 21:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:05:46 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)) 21:05:46 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:05:46 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:05:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:05:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:05:48 INFO - Downloading blessings-1.6.tar.gz 21:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:05:48 INFO - Installing collected packages: blessings 21:05:48 INFO - Running setup.py install for blessings 21:05:48 INFO - Successfully installed blessings 21:05:48 INFO - Cleaning up... 21:05:48 INFO - Return code: 0 21:05:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:05:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:05:48 INFO - Reading from file tmpfile_stdout 21:05:48 INFO - Current package versions: 21:05:48 INFO - argparse == 1.2.1 21:05:48 INFO - blessings == 1.6 21:05:48 INFO - blobuploader == 1.2.4 21:05:48 INFO - docopt == 0.6.1 21:05:48 INFO - functools32 == 3.2.3-2 21:05:48 INFO - jsonschema == 2.5.1 21:05:48 INFO - manifestparser == 1.1 21:05:48 INFO - mozInstall == 1.12 21:05:48 INFO - mozcrash == 0.17 21:05:48 INFO - mozdebug == 0.1 21:05:48 INFO - mozdevice == 0.48 21:05:48 INFO - mozfile == 1.2 21:05:48 INFO - mozhttpd == 0.7 21:05:48 INFO - mozinfo == 0.9 21:05:48 INFO - mozleak == 0.1 21:05:48 INFO - mozlog == 3.3 21:05:48 INFO - moznetwork == 0.27 21:05:48 INFO - mozprocess == 0.23 21:05:48 INFO - mozprofile == 0.28 21:05:48 INFO - mozrunner == 6.12 21:05:48 INFO - mozscreenshot == 0.1 21:05:48 INFO - mozsystemmonitor == 0.3 21:05:48 INFO - moztest == 0.7 21:05:48 INFO - mozversion == 1.4 21:05:48 INFO - psutil == 3.1.1 21:05:48 INFO - requests == 1.2.3 21:05:48 INFO - wsgiref == 0.1.2 21:05:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 21:05:48 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:05:48 INFO - Using env: {'DISPLAY': ':0', 21:05:48 INFO - 'HOME': '/home/cltbld', 21:05:48 INFO - 'LANG': 'en_US.UTF-8', 21:05:48 INFO - 'LANGUAGE': 'en_US:en', 21:05:48 INFO - 'LOGNAME': 'cltbld', 21:05:48 INFO - 'MAIL': '/var/mail/cltbld', 21:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:48 INFO - 'MOZ_NO_REMOTE': '1', 21:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:48 INFO - 'NO_EM_RESTART': '1', 21:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:48 INFO - 'PWD': '/builds/slave/test', 21:05:48 INFO - 'SHELL': '/bin/bash', 21:05:48 INFO - 'SHLVL': '1', 21:05:48 INFO - 'TERM': 'linux', 21:05:48 INFO - 'TMOUT': '86400', 21:05:48 INFO - 'USER': 'cltbld', 21:05:48 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:48 INFO - '_': '/tools/buildbot/bin/python'} 21:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 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)) 21:05:49 INFO - Cleaning up... 21:05:49 INFO - Return code: 0 21:05:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 21:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:05:49 INFO - Using env: {'DISPLAY': ':0', 21:05:49 INFO - 'HOME': '/home/cltbld', 21:05:49 INFO - 'LANG': 'en_US.UTF-8', 21:05:49 INFO - 'LANGUAGE': 'en_US:en', 21:05:49 INFO - 'LOGNAME': 'cltbld', 21:05:49 INFO - 'MAIL': '/var/mail/cltbld', 21:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:49 INFO - 'MOZ_NO_REMOTE': '1', 21:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:49 INFO - 'NO_EM_RESTART': '1', 21:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:49 INFO - 'PWD': '/builds/slave/test', 21:05:49 INFO - 'SHELL': '/bin/bash', 21:05:49 INFO - 'SHLVL': '1', 21:05:49 INFO - 'TERM': 'linux', 21:05:49 INFO - 'TMOUT': '86400', 21:05:49 INFO - 'USER': 'cltbld', 21:05:49 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:49 INFO - '_': '/tools/buildbot/bin/python'} 21:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:05:49 INFO - Cleaning up... 21:05:49 INFO - Return code: 0 21:05:49 INFO - Running post-action listener: _resource_record_post_action 21:05:49 INFO - Running post-action listener: _start_resource_monitoring 21:05:49 INFO - Starting resource monitoring. 21:05:49 INFO - [mozharness: 2016-08-11 04:05:49.397711Z] Finished create-virtualenv step (success) 21:05:49 INFO - [mozharness: 2016-08-11 04:05:49.398152Z] Running install step. 21:05:49 INFO - Running pre-action listener: _resource_record_pre_action 21:05:49 INFO - Running main action method: install 21:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:05:49 INFO - Reading from file tmpfile_stdout 21:05:49 INFO - Detecting whether we're running mozinstall >=1.0... 21:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:05:49 INFO - Reading from file tmpfile_stdout 21:05:49 INFO - Output received: 21:05:49 INFO - Usage: mozinstall [options] installer 21:05:49 INFO - Options: 21:05:49 INFO - -h, --help show this help message and exit 21:05:49 INFO - -d DEST, --destination=DEST 21:05:49 INFO - Directory to install application into. [default: 21:05:49 INFO - "/builds/slave/test"] 21:05:49 INFO - --app=APP Application being installed. [default: firefox] 21:05:49 INFO - mkdir: /builds/slave/test/build/application 21:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:06:06 INFO - Reading from file tmpfile_stdout 21:06:06 INFO - Output received: 21:06:06 INFO - /builds/slave/test/build/application/firefox/firefox 21:06:06 INFO - Running post-action listener: _resource_record_post_action 21:06:06 INFO - [mozharness: 2016-08-11 04:06:06.754872Z] Finished install step (success) 21:06:06 INFO - [mozharness: 2016-08-11 04:06:06.755120Z] Running run-tests step. 21:06:06 INFO - Running pre-action listener: _resource_record_pre_action 21:06:06 INFO - Running main action method: run_tests 21:06:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:06:06 INFO - Python 2.7.3 21:06:06 INFO - Return code: 0 21:06:06 INFO - grabbing minidump binary from tooltool 21:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 21:06:06 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/linux64/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 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:06:06 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:06:06 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/linux64/releng.manifest -o -c /builds/tooltool_cache 21:06:06 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:06:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:06:06 INFO - Return code: 0 21:06:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:06:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:06:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:06:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:06:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:06:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:06:06 INFO - Using env: {'DISPLAY': ':0', 21:06:06 INFO - 'HOME': '/home/cltbld', 21:06:06 INFO - 'LANG': 'en_US.UTF-8', 21:06:06 INFO - 'LANGUAGE': 'en_US:en', 21:06:06 INFO - 'LOGNAME': 'cltbld', 21:06:06 INFO - 'MAIL': '/var/mail/cltbld', 21:06:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:06:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:06:06 INFO - 'MOZ_NO_REMOTE': '1', 21:06:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:06:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:06:06 INFO - 'NO_EM_RESTART': '1', 21:06:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:06:06 INFO - 'PWD': '/builds/slave/test', 21:06:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:06:06 INFO - 'SHELL': '/bin/bash', 21:06:06 INFO - 'SHLVL': '1', 21:06:06 INFO - 'TERM': 'linux', 21:06:06 INFO - 'TMOUT': '86400', 21:06:06 INFO - 'USER': 'cltbld', 21:06:06 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1470888079.739826-211675254', 21:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:06:06 INFO - '_': '/tools/buildbot/bin/python'} 21:06:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:06:07 INFO - mozversion application_buildid: 20160810193025 21:06:07 INFO - mozversion application_changeset: 0502bd9e025edde29777ba1de4280f9b52af4663 21:06:07 INFO - mozversion application_display_name: Nightly 21:06:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:06:07 INFO - mozversion application_name: Firefox 21:06:07 INFO - mozversion application_remotingname: firefox 21:06:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:06:07 INFO - mozversion application_vendor: Mozilla 21:06:07 INFO - mozversion application_version: 51.0a1 21:06:07 INFO - mozversion platform_buildid: 20160810193025 21:06:07 INFO - mozversion platform_changeset: 0502bd9e025edde29777ba1de4280f9b52af4663 21:06:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:06:07 INFO - mozversion platform_version: 51.0a1 21:06:07 INFO - using testdate: 1470888367 21:06:07 INFO - actual date: 1470888367 21:06:07 INFO - starting webserver on 'localhost:38156' 21:06:07 INFO - SUITE-START | Running 2 tests 21:06:07 INFO - TEST-START | dromaeo_css 21:06:07 INFO - operating with platform_type : linux_ 21:06:07 INFO - Initialising browser for dromaeo_css test... 21:06:07 INFO - TEST-INFO | started process 8978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOq937S/profile http://localhost:38156/getInfo.html) 21:06:10 INFO - PROCESS | 8978 | __metrics Screen width/height:1600/1200 21:06:10 INFO - PROCESS | 8978 | colorDepth:24 21:06:10 INFO - PROCESS | 8978 | Browser inner width/height: 1024/697 21:06:10 INFO - PROCESS | 8978 | __metrics 21:06:11 INFO - PROCESS | 8978 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:06:11 INFO - PROCESS | 8978 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:06:12 INFO - TEST-INFO | 8978: exit 0 21:06:12 INFO - Browser initialized. 21:06:12 INFO - Running cycle 1/1 for dromaeo_css test... 21:06:12 INFO - TEST-INFO | started process 9060 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOq937S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:06:24 INFO - PROCESS | 9060 | RSS: Main: 155516928 21:06:24 INFO - PROCESS | 9060 | 21:07:14 INFO - PROCESS | 9060 | 1470888434873 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:09:25 INFO - PROCESS | 9060 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38156/tests/dromaeo/cssquery-ext.html) 21:09:26 INFO - PROCESS | 9060 | RSS: Main: 261316608 21:09:26 INFO - PROCESS | 9060 | 21:12:29 INFO - PROCESS | 9060 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-ext.html (next: http://localhost:38156/tests/dromaeo/cssquery-jquery.html) 21:12:29 INFO - PROCESS | 9060 | RSS: Main: 241041408 21:12:29 INFO - PROCESS | 9060 | 21:15:31 INFO - PROCESS | 9060 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38156/tests/dromaeo/cssquery-mootools.html) 21:15:32 INFO - PROCESS | 9060 | RSS: Main: 185257984 21:15:32 INFO - PROCESS | 9060 | 21:18:34 INFO - PROCESS | 9060 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38156/tests/dromaeo/cssquery-prototype.html) 21:18:35 INFO - PROCESS | 9060 | RSS: Main: 183234560 21:18:35 INFO - PROCESS | 9060 | 21:21:37 INFO - PROCESS | 9060 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38156/tests/dromaeo/cssquery-yui.html) 21:21:38 INFO - PROCESS | 9060 | RSS: Main: 184012800 21:21:38 INFO - PROCESS | 9060 | 21:24:41 INFO - PROCESS | 9060 | Cycle 1(1): loaded http://localhost:38156/tests/dromaeo/cssquery-yui.html (next: http://localhost:38156/tests/dromaeo/cssquery-dojo.html) 21:24:42 INFO - PROCESS | 9060 | RSS: Main: 274153472 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | __start_tp_report 21:24:42 INFO - PROCESS | 9060 | _x_x_mozilla_page_load 21:24:42 INFO - PROCESS | 9060 | _x_x_mozilla_page_load_details 21:24:42 INFO - PROCESS | 9060 | |i|pagename|runs| 21:24:42 INFO - PROCESS | 9060 | |0;dojo.html;3077.107638784752;3093.8453077346126;3115.43922094023;3116.454620441423;3132.9530057049133;5277.445868183843;5334.839954801358;5356.223347614594;5382.569394448445;5438.265834112393;5274.789008439668;5290.153575427932;5329.813456529013;5352.2239275305055;5376.354837419505;4752.12085764133;4838.782254798542;4846.660733748639;4911.705297682151;5098.056859481007;5032.57223136032;5050.520200580939;5060.266261392096;5096.719680417576;5102.617303702237;4757.26262429324;4833.202521583935;4851.611871050308;4878.707277563322;4926.408830940292;4780.115678599435;4798.784054717511;4805.975970120162;4807.735574543399;4809.6392770542;4767.833125840579;4805.735684537349;4844.5155484451625;4885.975570122162;4903.975480122576;5304.92042619361;5327.200919862013;5336.573074154058;5340.599455040888;5378.0857254266475;5761;5861.794837180721;5861.85345366365;5865.85335366615;5866.648001119904;5853.385394533555;5910.320313163998;5911.970440147772;5922.437368449991;5923.822285331447;5230.189320655304;5320.787168513293;5366.302380690563;5489.917651235235;5496.31296087989;5900.557458190653;5907.23205983228;5940.940590594038;5957.4042595740075;5960.433758792908;5657.57568182388;5663.235463212496;5667.546596272288;5714.171445140514;5716.171155182476;5650.519705825051;5727.742251598688;5768.682722450221;5792.9420705793245;5793.275840519935;5709.172170035322;5711.143328500758;5714.885702285931;5750.1949727038245;5764.048931926185;5680.488756011979;5720.971395142998;5723.77104915807;5725.541956643458;5734.111212762028;4312.353147027971;4371;4393.538678438782;4402.097569998143;4410.051838854662;3943.723939324277;3956.584558621303;3974.026363540951;4023.9195216095513;4037.7779222142476;5450.127979523258;5456.91814622781;5462.344518657707;5471.644343117685;5518.034343989786;5644.266245388073;5756.740946657411;5790;5815.82552523425;5817.88364232713;5482.671039737549;5536.252605898233;5541.972290138524;5586.441355864462;5595.7481913313995;5078.415982162099;5078.97460512695;5085.338905942203;5086.821961231339;5143.434222235551;5850.180974663465;6008.8798224036145;6089.1779609753;6140.048292514667;6144.201253836973;5768.192453056491;5852.268466441695;5854.941450585439;5898.262717160355;5905.025670764448;6075.574709770273;6091.390860914051;6094.9085763714465;6096.786612468542;6097.146399503984;5553.389127196086;5557.3331200256225;5672.347680016685;5738.254026976467;5744.3681195067675;5677.3754886963225;5775.94224057754;5776.191333213437;5810.4189581041555;5823.563232757476;5602.971985140048;5644.125160600113;5644.6613203207935;5657.094864821609;5658.094704847204;5437.02133615953;5520.66875987442;5616.326040875121;5626.212330273846;5630.549556035589;5467.535259502828;5595.412481689364;5631.408702086222;5641;5648.5763567731765;5666.263385759825;5668.404817494104;5671.546276297804;5718.942810571841;5725.6850873202375;5370.838874833761;5374.570034397317;5553.694546799965;5559.63862348946;5561.694106824163 21:24:42 INFO - PROCESS | 9060 | |1;ext.html;592.6207227374479;608.3460280198786;612.95709300349;616.9568130230883;640.5900223856729;43244.35133473;44378.11243775127;44598;48109.27836082462;48282.27576586345;46263;46665.533344666546;46669.29996050062;46756.29865552019;46766.766166169204;7963.0046244219475;8965.237954773853;9110.225630821387;9111.589978450953;10804.351738895652;10555.841662375033;11003.61487347939;11012.063974562172;11012.119030477583;11012.339259644406;11006.889931100666;11048.281861678965;11058.336499809997;11072.723181920435;11074.169437292196;10959.232853700245;11007;11020.724481887937;11030.338179709242;11036.006759391694;10776.78446431067;10882.945585272102;10883.074938630227;10888.128949684005;10889.782204355948;42493;42903;42911.785441072905;42919;42969.57030429718;3500.142465096042;3623.3115708015657;3624.1302087499075;3633.1280492681567;3905.394663827083;33317.83341083303;33422.665773342196;33997;34088.48867266993;35103;4321.7406955582765;4438.911221775546;4454.821807127711;4564.155631208204;4587.472440669332;365980;367445;368389;368532.9999999973;368803;58736.41263587395;58928.41071589229;58969.705151473965;59156.40843591595;59235;61088.38911610916;61099;61142.69428652917;61162;61187.694061530296;14483.131012139305;14484.782728259084;14527.273636318141;14535.345909434101;14552.49066282675;12282.38588070593;13017.869821301856;13397;13399.799003014963;13469.797953030713;16334.18329083541;16347.918260408622;16474.83525164757;16561.585960350847;16680.416185433452;6092.7906639823195;6178.196834411498;6273.811785646438;6283.403076707706;6306.684665766693;976.82417164911;1079.4548752879746;1087.407362987158;1120.2270433400927;1195.0738177912015;32868.01395958125;33292;33293;33293.50059749106;33756.83121584426;1078.2883297023927;1146.4497041420166;1153.8038533449335;1167.6146871532374;1259.1878238536276;16506.83493165077;17265.705072119643;17324;17389.739153912702;17629.735553966446;515592;519987;520572;520848;522180;52370.73814630903;52373.7381313091;52787.73606132022;52807.735961319944;52847;1195.36048214205;1325.827642406481;1329.654289884637;1359.7348517039081;1368.890488760877;32853.83573082119;34039;34569.65430345664;34735;35174.824125879204;50122;50612.493875060776;50704.746476267384;50717.49282507275;50826;421184;422826;424054;424339;424595;27629.861850690617;28698.13905582836;28761.856190718914;29706.405871882875;29979.400411992076;48546.51453485561;48899.26651100165;49214.261786073956;50094.24858627051;50643;40098.59901400949;41074.383884241164;41706.79146604247;42255.7887210562;44087.77956110199;41379.58620413757;41814.372784408864;41875.7906210467;42804.78597606992;42906.78546607372;40764.38853417142;41868.581314187686;41926.79036604797;42612.360814587184;45822.31266531072;33500.32999340048;37996.81001594974;38024.80987595044;38482.830852098676;38927.41608875926;27986.361772969383;28297.57553636656;28800.711992879802;28900.554972250884;29183 21:24:42 INFO - PROCESS | 9060 | |2;jquery.html;375.11847159175954;398.64520576686755;400.507375927609;408.83646541383445;422.8667969589579;14033;18656.440306790784;19794.70307945382;20115;20154.294599689012;12473.563425280117;13171.143875648077;13207.801882971764;13278.867211327908;13304;4050.736702114353;5415.512603865652;5561.4716601922755;5578.386377498472;5583.497485226349;4206.600372964564;4275.144971005796;4275.593818587229;5781.971090144543;6052.122442245872;6047.244094488189;6079.270487541501;6106.511479081662;6109.663968481732;6630.801075967729;6610.801675949705;6711.026901099338;6719.563228390139;6726.764563240312;6746.055552222692;5941.55438342126;5953.642781433128;6032.733126043536;6064.06007068905;6069.393060693939;14594.270286485633;14726.631834204123;14849.331780069922;20663.760174389583;23808.880955595283;2318.1654604342425;2411.3730430088126;2478.1945867593104;2675.973240267592;3011;14303.642408939755;14452.78320825178;14970.47603333878;15211.163386013764;15659.13874736889;1986.960260794776;1999.2402886902885;2085.979140208594;2088.989555052215;2115.6932244824416;83757.16242837555;83816;84038;85737.57131214367;86531;11161.609343673095;11528;11592.826107608393;11597.826032609517;11604.30374177552;15915.602109947113;16013;16097.436589719538;16120.274587643584;16157.515274541782;8272.33821294307;8374.623141958626;8492.617832197566;8559.871601925975;8596.269317108116;8073.354131669453;8171.673133074611;8219.383546234056;8318.918540589684;8400.957995210107;4835.323054772335;4897.706137631753;4946.233333833262;4990.90018199634;5031.7987280509105;2017.939461816148;2031.3905828251466;2065.3390914907086;2101.5271563898305;2125.691774692661;917.8898532176181;1007.2596641468515;1045.3309881675734;1052.2844465763355;1066.648006157966;19142.61714765697;19516.219351226075;20151.697724534144;20204.898975505028;20824.47938801512;955.8709574207393;957.7749228931195;979;982.415462799633;1013.0123129948241;10548.208884333706;11125.276857004268;11146.386948717736;11180.575242585632;11269.774604508028;466380;472365;479540;492924;494185;90420;90827.54586227027;92281;92434.53782731178;92923.53538232266;973.445136272318;980.8970058143792;991.0832479956011;995.0149352141473;997.9501024948723;16042.598935026717;16884.831151688326;17515.649687005935;17836.910815446357;18087.728684070014;42073.36889946715;42465.36301955411;42782.78608607061;43234;44040.55959440493;57460.71269643625;59016.704916475144;59163;59807.401925981925;60538.39461605327;8237.299829514559;8244.505329680238;8570.614322355385;8609.526476043879;8650.717894642192;12449.93775031119;13025.348732563523;13101.475940962255;13191;13381.933090334485;11417.37204453763;11633.825492617787;11698.415079246173;12216.633500994983;12227.0829687772;8272.214139656722;11287.830682539932;11305.208635395444;11428.771424571296;11690.123240756808;11207.775844483227;11471.197016208785;11485.598004069818;12026.27842329463;12101.636950891487;8617.95691021566;8770.649174032967;8778.34162437807;8789.945206575252;8925.866112008454;10378.740531486774;11914.10644201671;11940.880591193976;11994.76010479768;11998.700032499257 21:24:42 INFO - PROCESS | 9060 | |3;mootools.html;1330.687288487206;1455.6506438454771;1518.2105305241275;1636.7381219004963;1785.4554360919922;8413.663453461862;8824;8843.557822108893;9071.365004449692;9087.227585655228;8925.687600933968;9014.774630634238;9024.81950360994;9033.277337812975;9038.72883813485;2428.8785560721935;2448.816338774599;2454.349597356693;2454.619533972237;2455.84037037592;2323.082382458928;2336.918207862725;2341.075275266269;2342.16853017179;2392.963839766904;2383.8688872112025;2384.5350156719446;2386.725526564441;2414.239514552919;2419.9516009679796;2316.5366926614743;2345.9648105278434;2348.765123487655;2353.564590550745;2372.893219805113;2336.5210131922913;2356.8821558921986;2364.9527009459716;2367.0886708617136;2369.988150059256;8920.063393343671;8958.148975847284;9007.369484136114;9060.275177985744;9069.863952040725;5292.259083728281;5516.9448305516835;5526.723663816793;5529.115341545334;5545.667259964415;3438.6561343865665;3467.9479807802904;3512.59605145409;3519.04985653872;3524.806135662563;1147.1912301827192;1150.4075401168404;1163.2438914705424;1171.707073231692;1180.586794621884;12525.123241373107;12556.99544036484;12594.496220151183;12665.176763510342;12676.492940282378;4489.842855500042;4663.183942809995;4760.262159365304;4778.354922085544;4780.617550595944;9946.154576861049;9978.750531236632;9997.500124993721;10006.149477294366;10013.64952226683;5381.327334083239;5392.137258038773;5400.405955344909;5400.540954018874;5402.081646120169;4155.854545090907;4415.4259946206785;4536.933820552167;4929.088118698052;5051.267566202953;3863.3046051710962;3864.8840534784003;3957.248122856648;3963.088489647397;3976.443297938291;1151.654503648902;1167.1888037813808;1173.0263880978766;1177.7821103095955;1182.7338848758961;852.411835833273;936.8969413364524;964.064857088623;972.547765289144;988.8912219655832;6073.544484163617;6116.60242084272;6220.098085777537;6322;6345.555811093179;471.7476150259595;471.92685133804315;473.9502352253033;474.3027749208658;475.11628371229483;4098.262312783728;4140.192662430857;4140.4617399738445;4166.229247589205;4179.791010449465;10946.233763636468;11470;11477.196596238351;11482.77034459323;11550;11362.034227090624;11392.088632909348;11392.658220253406;11393;11397.943010285062;7969.641366138538;8188.181181881883;8248.711295104762;8456.830863382698;8477.321814254969;6038.909416358671;6060.272767267956;6250.312465628686;6295.905561416674;6314.358842086533;10405.791884162425;10509.474526273503;10577.9471102644;10708.196885233483;10714.089302409382;9433.386829856037;10440;10518.211134164816;10682.89317106819;10774.515146818256;7823.413244006609;8021.679132834622;8046.114927358103;8237.217464340878;8275.668973241003;8894.777630559287;8897.332700047651;8911.06433824465;8923.241524470493;8995.505247211204;7797.298243158143;7815.335696465637;7839.84320313602;7897.973263475711;8029.5583742894705;7830.412719046209;7905.130435652189;7935;7955.04539455246;8021.759347219593;7303.8539229216185;7311.086114235721;7357.227491113356;7357.484976051625;7366.447516436181;5268;5296.285001524745;5403.0814761489855;5418.160185171305;5425.511703946664;5428.891422171613;5481.177193745145;5492.615516913777;5606.411326810626;5637.8590535236945 21:24:42 INFO - PROCESS | 9060 | |4;prototype.html;334.36136978371303;337.7770671356905;342.5102103991293;344.6019847076627;348.5190437196668;5318.175682769167;5340.813071542497;5372.382176049756;5380.461953804617;5402.78388864446;5269.394019687737;5300.946990530094;5317.564257650432;5337.01265265926;5346.705931173784;4025.29557327468;4042.454268673721;4140.482439695038;4183.933097060245;4206.642435392995;4173.977375542996;4180.3729440584;4181.163767246548;4205.600467955539;4206.8737937861915;4149.439825623533;4156.584341565849;4168.958310416887;4173.353130264799;4188.455500784894;3946.9605303947023;4011.5587285398583;4142.79286035697;4166.937495937564;4182.2890108681295;3695.4826324314618;3706.962930370689;3715.38380804351;3717.7955212463303;3719.758215715997;5161.561267292284;5175.430702622746;5183.377994640629;5213.895722085537;5220.504052115043;4498.707584007062;4724.95275047252;4733.290006499018;4750.524947505256;4776.044791041771;5454.281901201109;5500.367457742371;5522.502974732293;5523.475269849358;5536.363318218416;3994.1612261425134;3994.9201015979806;4007.7595344279434;4018.618231268054;4021;5534.640248383842;5708.628939118944;5720.971395143039;5742.425757424266;5775.191473193756;4073.45008423859;4077.2864748668862;4090.4886889138857;4098.663761655827;4100.876973690794;4143.7306575072525;4149.668026557868;4151.398047283187;4155.877912963483;4156.189543039079;4573.474050484237;4710.717356958594;4723.6929599575915;4747.240441529339;4752.2158843790385;5499.285092937893;5521.061419558685;5525.673838278864;5543.752655652446;5621.353544342453;1407.9862499000703;1431.6206205355381;1435.5980325508874;1440.6038339456734;1440.7118576284786;867.3624885708999;919.94940278284;925.2181906289175;937.3906960475609;939.8026414452904;5238.711870847063;5335.813246536352;5343.893122137613;5345.663584103974;5362.383325917492;5649.06790379591;5650;5657.415923541415;5660.462256085665;5675.801346952837;5102.260172275;5311.336082989626;5339.946600533945;5344.652597581223;5365.356157261153;2968.4656761783085;3010.3527741535677;3017.698230176965;3021.6676165621766;3027.455058089521;5933.881322373615;5936.5547583931375;5950.345461999176;5962.552808539377;5971.14894382734;5587.38538760736;5766.913496297559;5825.4174582542255;5828.708564571839;5832.862591794644;5918.763249469973;5924.7926322578505;5932.911006334908;5936.673482958478;5947.643141411356;5444.482774136451;5646.802361917313;5686.43135686428;5727.59208271078;5731.971340143273;5642.971785141212;5670.404607516316;5676.5742569308295;5680.68756218412;5687.857803554944;5941.524678025661;5956.855532008387;5961.165436838933;6004.369541198286;6016.7292471838;3988.9202215956097;4095.2833254180996;4097.160082183085;4110.609492098365;4141.254574176677;5684.77260909559;5727.828165155052;5729.427057294237;5738.512226460798;5761.934042202206;5477.616566840283;5631.887362252814;5633.8309850704545;5660.51885589713;5696.458836410535;5584.301962254718;5595.468430499096;5596.384397716167;5604.271444712162;5652.265205523256;5546.334439867081;5549.472800083986;5560.248521715646;5560.332760068818;5572.86067848307;5376.19357096423;5430.864228394322;5525.751341189576;5535.8892822142525;5541.2796336476;5389.83830485086;5422.755975981011;5441.809536666197;5441.972790136024;5450.155225939986 21:24:42 INFO - PROCESS | 9060 | |5;yui.html;748.8577170337638;788.9487183333083;804.1074407407773;813.5850716134771;836.799168199632;14499.710005799878;15443.227838608052;15516.30176642051;15536.766948495782;16182.109983950879;15671.92164039181;15954.920225398857;15993.760093598576;15995.760063599055;16027;658.4830907737403;812.2446125103666;834.3367023216557;837.6816809612338;838.4424357802054;804.899387576553;837.543538771368;837.5560952695081;839.8446287436814;841.5750046226647;867.0765634599163;868.9348298877578;869.3479890082439;869.42183448007;869.5261082709907;696.8432102776885;865.6623916672503;865.9653613855438;866.0603245478632;866.0862789756804;605.5791225098558;628.1864984844592;630.8706715123388;632.1466020871832;632.2413104274889;15289;15319.923400383039;15324.693506129926;15339.539813805492;15344.386224551115;13674.658133546742;13703.725925481534;13770.311484425738;13838.654033649138;14586.416543338253;11408.942955285254;11465;11564.53741850325;12634.81047784284;12643.873561264454;4539.182947069559;4553.408056952575;4567.703099298568;4789.090072886141;4792.496787837262;67916;70470.64764676195;71883;78950.60524697397;79675;11359.393000436996;11545.538178472767;11838.763224735458;11890.940545297219;12099.879001209874;22203.333899983027;22608.17397825272;22642.207522736626;22650.43373915665;22659.773402266095;8644.610992505352;8789.868151977726;8929.374943754005;9225.446473211628;9265.490398028038;4771.904561908743;4800.255960326155;4823.204171311764;4832.758362081881;4833.879153021203;3553.200529880808;3724.4413337999517;3727.7576957497677;3730.869419570302;3734;607.9421806057428;612.9478994285447;639.3957709964133;639.4947991087082;642.8874946884276;548.0049350173143;584.915187297848;594.9643021418642;625.7371903800415;683.2484267306023;9093.499857507768;9736.464494452874;9889.604415823287;9958.539149755074;10404.75142982847;597.5040716205539;659.650385295794;692.7817737412698;717.1430140981581;732.1847496376424;4295.162443323522;4501.639868810553;4523.479799822997;4593.333966574828;4684.1568517666465;109920;130188;131701.34149329193;133533;136320;105801;108841.45579272212;110024;113110.43444782724;115439;78619.60690196512;79994.20005799984;80041.59979200183;95222.52388738011;98532;9527.523623818919;9725.9027409725;9966;10193.898061019592;10337.741556460847;51054;51384.74307628438;52983.73508132435;54052;55198.44801552094;66078.66960665166;66342.33657663554;68507.3149268521;76662.23337766551;77574;12950.935245323713;12976.870231297944;13160.41742205623;13394.598162055154;13501.662458438617;15375.538733838002;15430.5370838875;15520.14639194855;15684.843151568795;15747;13741.519046833313;14075.507357242448;14182.716345673236;14269.785953210503;14606.561803145923;12646.936765316115;12687.80968285495;12792.70971228518;13238.338083096;13386.26375549354;14103.224322662352;14270.571882843531;14484.130952142905;14502.709945801236;14533.491327803476;11398.487068081791;11579.768404632028;11736.178467507534;12338.568150114703;12589.93705031469;10928.672139835817;11838.112141589243;11997.700057498632;12295.446704898122;12398.194117382342 21:24:42 INFO - PROCESS | 9060 | __end_tp_report 21:24:42 INFO - PROCESS | 9060 | __start_cc_report 21:24:42 INFO - PROCESS | 9060 | _x_x_mozilla_cycle_collect,1016 21:24:42 INFO - PROCESS | 9060 | __end_cc_report 21:24:42 INFO - PROCESS | 9060 | __startTimestamp1470889482048__endTimestamp 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | ------- Summary: start ------- 21:24:42 INFO - PROCESS | 9060 | Number of tests: 6 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | [#0] dojo.html Cycles:165 Average:5368.44 Median:5561.69 stddev:620.76 (11.2%) stddev-sans-first:596.07 21:24:42 INFO - PROCESS | 9060 | Values: 3077.1 3093.8 3115.4 3116.5 3133.0 5277.4 5334.8 5356.2 5382.6 5438.3 5274.8 5290.2 5329.8 5352.2 5376.4 4752.1 4838.8 4846.7 4911.7 5098.1 5032.6 5050.5 5060.3 5096.7 5102.6 4757.3 4833.2 4851.6 4878.7 4926.4 4780.1 4798.8 4806.0 4807.7 4809.6 4767.8 4805.7 4844.5 4886.0 4904.0 5304.9 5327.2 5336.6 5340.6 5378.1 5761.0 5861.8 5861.9 5865.9 5866.6 5853.4 5910.3 5912.0 5922.4 5923.8 5230.2 5320.8 5366.3 5489.9 5496.3 5900.6 5907.2 5940.9 5957.4 5960.4 5657.6 5663.2 5667.5 5714.2 5716.2 5650.5 5727.7 5768.7 5792.9 5793.3 5709.2 5711.1 5714.9 5750.2 5764.0 5680.5 5721.0 5723.8 5725.5 5734.1 4312.4 4371.0 4393.5 4402.1 4410.1 3943.7 3956.6 3974.0 4023.9 4037.8 5450.1 5456.9 5462.3 5471.6 5518.0 5644.3 5756.7 5790.0 5815.8 5817.9 5482.7 5536.3 5542.0 5586.4 5595.7 5078.4 5079.0 5085.3 5086.8 5143.4 5850.2 6008.9 6089.2 6140.0 6144.2 5768.2 5852.3 5854.9 5898.3 5905.0 6075.6 6091.4 6094.9 6096.8 6097.1 5553.4 5557.3 5672.3 5738.3 5744.4 5677.4 5775.9 5776.2 5810.4 5823.6 5603.0 5644.1 5644.7 5657.1 5658.1 5437.0 5520.7 5616.3 5626.2 5630.5 5467.5 5595.4 5631.4 5641.0 5648.6 5666.3 5668.4 5671.5 5718.9 5725.7 5370.8 5374.6 5553.7 5559.6 5561.7 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | [#1] ext.html Cycles:180 Average:60420.90 Median:38010.81 stddev:116743.98 (307.1%) stddev-sans-first:116985.06 21:24:42 INFO - PROCESS | 9060 | Values: 592.6 608.3 613.0 617.0 640.6 43244.4 44378.1 44598.0 48109.3 48282.3 46263.0 46665.5 46669.3 46756.3 46766.8 7963.0 8965.2 9110.2 9111.6 10804.4 10555.8 11003.6 11012.1 11012.1 11012.3 11006.9 11048.3 11058.3 11072.7 11074.2 10959.2 11007.0 11020.7 11030.3 11036.0 10776.8 10882.9 10883.1 10888.1 10889.8 42493.0 42903.0 42911.8 42919.0 42969.6 3500.1 3623.3 3624.1 3633.1 3905.4 33317.8 33422.7 33997.0 34088.5 35103.0 4321.7 4438.9 4454.8 4564.2 4587.5 365980.0 367445.0 368389.0 368533.0 368803.0 58736.4 58928.4 58969.7 59156.4 59235.0 61088.4 61099.0 61142.7 61162.0 61187.7 14483.1 14484.8 14527.3 14535.3 14552.5 12282.4 13017.9 13397.0 13399.8 13469.8 16334.2 16347.9 16474.8 16561.6 16680.4 6092.8 6178.2 6273.8 6283.4 6306.7 976.8 1079.5 1087.4 1120.2 1195.1 32868.0 33292.0 33293.0 33293.5 33756.8 1078.3 1146.4 1153.8 1167.6 1259.2 16506.8 17265.7 17324.0 17389.7 17629.7 515592.0 519987.0 520572.0 520848.0 522180.0 52370.7 52373.7 52787.7 52807.7 52847.0 1195.4 1325.8 1329.7 1359.7 1368.9 32853.8 34039.0 34569.7 34735.0 35174.8 50122.0 50612.5 50704.7 50717.5 50826.0 421184.0 422826.0 424054.0 424339.0 424595.0 27629.9 28698.1 28761.9 29706.4 29979.4 48546.5 48899.3 49214.3 50094.2 50643.0 40098.6 41074.4 41706.8 42255.8 44087.8 41379.6 41814.4 41875.8 42804.8 42906.8 40764.4 41868.6 41926.8 42612.4 45822.3 33500.3 37996.8 38024.8 38482.8 38927.4 27986.4 28297.6 28800.7 28900.6 29183.0 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | [#2] jquery.html Cycles:180 Average:28802.37 Median:399.58 stddev:79478.03 (19890.6%) stddev-sans-first:79672.32 21:24:42 INFO - PROCESS | 9060 | Values: 375.1 398.6 400.5 408.8 422.9 14033.0 18656.4 19794.7 20115.0 20154.3 12473.6 13171.1 13207.8 13278.9 13304.0 4050.7 5415.5 5561.5 5578.4 5583.5 4206.6 4275.1 4275.6 5782.0 6052.1 6047.2 6079.3 6106.5 6109.7 6630.8 6610.8 6711.0 6719.6 6726.8 6746.1 5941.6 5953.6 6032.7 6064.1 6069.4 14594.3 14726.6 14849.3 20663.8 23808.9 2318.2 2411.4 2478.2 2676.0 3011.0 14303.6 14452.8 14970.5 15211.2 15659.1 1987.0 1999.2 2086.0 2089.0 2115.7 83757.2 83816.0 84038.0 85737.6 86531.0 11161.6 11528.0 11592.8 11597.8 11604.3 15915.6 16013.0 16097.4 16120.3 16157.5 8272.3 8374.6 8492.6 8559.9 8596.3 8073.4 8171.7 8219.4 8318.9 8401.0 4835.3 4897.7 4946.2 4990.9 5031.8 2017.9 2031.4 2065.3 2101.5 2125.7 917.9 1007.3 1045.3 1052.3 1066.6 19142.6 19516.2 20151.7 20204.9 20824.5 955.9 957.8 979.0 982.4 1013.0 10548.2 11125.3 11146.4 11180.6 11269.8 466380.0 472365.0 479540.0 492924.0 494185.0 90420.0 90827.5 92281.0 92434.5 92923.5 973.4 980.9 991.1 995.0 998.0 16042.6 16884.8 17515.6 17836.9 18087.7 42073.4 42465.4 42782.8 43234.0 44040.6 57460.7 59016.7 59163.0 59807.4 60538.4 8237.3 8244.5 8570.6 8609.5 8650.7 12449.9 13025.3 13101.5 13191.0 13381.9 11417.4 11633.8 11698.4 12216.6 12227.1 8272.2 11287.8 11305.2 11428.8 11690.1 11207.8 11471.2 11485.6 12026.3 12101.6 8618.0 8770.6 8778.3 8789.9 8925.9 10378.7 11914.1 11940.9 11994.8 11998.7 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | [#3] mootools.html Cycles:180 Average:5940.02 Median:2617.69 stddev:3419.45 (130.6%) stddev-sans-first:3411.49 21:24:42 INFO - PROCESS | 9060 | Values: 1330.7 1455.7 1518.2 1636.7 1785.5 8413.7 8824.0 8843.6 9071.4 9087.2 8925.7 9014.8 9024.8 9033.3 9038.7 2428.9 2448.8 2454.3 2454.6 2455.8 2323.1 2336.9 2341.1 2342.2 2393.0 2383.9 2384.5 2386.7 2414.2 2420.0 2316.5 2346.0 2348.8 2353.6 2372.9 2336.5 2356.9 2365.0 2367.1 2370.0 8920.1 8958.1 9007.4 9060.3 9069.9 5292.3 5516.9 5526.7 5529.1 5545.7 3438.7 3467.9 3512.6 3519.0 3524.8 1147.2 1150.4 1163.2 1171.7 1180.6 12525.1 12557.0 12594.5 12665.2 12676.5 4489.8 4663.2 4760.3 4778.4 4780.6 9946.2 9978.8 9997.5 10006.1 10013.6 5381.3 5392.1 5400.4 5400.5 5402.1 4155.9 4415.4 4536.9 4929.1 5051.3 3863.3 3864.9 3957.2 3963.1 3976.4 1151.7 1167.2 1173.0 1177.8 1182.7 852.4 936.9 964.1 972.5 988.9 6073.5 6116.6 6220.1 6322.0 6345.6 471.7 471.9 474.0 474.3 475.1 4098.3 4140.2 4140.5 4166.2 4179.8 10946.2 11470.0 11477.2 11482.8 11550.0 11362.0 11392.1 11392.7 11393.0 11397.9 7969.6 8188.2 8248.7 8456.8 8477.3 6038.9 6060.3 6250.3 6295.9 6314.4 10405.8 10509.5 10577.9 10708.2 10714.1 9433.4 10440.0 10518.2 10682.9 10774.5 7823.4 8021.7 8046.1 8237.2 8275.7 8894.8 8897.3 8911.1 8923.2 8995.5 7797.3 7815.3 7839.8 7898.0 8029.6 7830.4 7905.1 7935.0 7955.0 8021.8 7303.9 7311.1 7357.2 7357.5 7366.4 5268.0 5296.3 5403.1 5418.2 5425.5 5428.9 5481.2 5492.6 5606.4 5637.9 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | [#4] prototype.html Cycles:180 Average:4693.64 Median:5363.87 stddev:1378.88 (25.7%) stddev-sans-first:1343.37 21:24:42 INFO - PROCESS | 9060 | Values: 334.4 337.8 342.5 344.6 348.5 5318.2 5340.8 5372.4 5380.5 5402.8 5269.4 5300.9 5317.6 5337.0 5346.7 4025.3 4042.5 4140.5 4183.9 4206.6 4174.0 4180.4 4181.2 4205.6 4206.9 4149.4 4156.6 4169.0 4173.4 4188.5 3947.0 4011.6 4142.8 4166.9 4182.3 3695.5 3707.0 3715.4 3717.8 3719.8 5161.6 5175.4 5183.4 5213.9 5220.5 4498.7 4725.0 4733.3 4750.5 4776.0 5454.3 5500.4 5522.5 5523.5 5536.4 3994.2 3994.9 4007.8 4018.6 4021.0 5534.6 5708.6 5721.0 5742.4 5775.2 4073.5 4077.3 4090.5 4098.7 4100.9 4143.7 4149.7 4151.4 4155.9 4156.2 4573.5 4710.7 4723.7 4747.2 4752.2 5499.3 5521.1 5525.7 5543.8 5621.4 1408.0 1431.6 1435.6 1440.6 1440.7 867.4 919.9 925.2 937.4 939.8 5238.7 5335.8 5343.9 5345.7 5362.4 5649.1 5650.0 5657.4 5660.5 5675.8 5102.3 5311.3 5339.9 5344.7 5365.4 2968.5 3010.4 3017.7 3021.7 3027.5 5933.9 5936.6 5950.3 5962.6 5971.1 5587.4 5766.9 5825.4 5828.7 5832.9 5918.8 5924.8 5932.9 5936.7 5947.6 5444.5 5646.8 5686.4 5727.6 5732.0 5643.0 5670.4 5676.6 5680.7 5687.9 5941.5 5956.9 5961.2 6004.4 6016.7 3988.9 4095.3 4097.2 4110.6 4141.3 5684.8 5727.8 5729.4 5738.5 5761.9 5477.6 5631.9 5633.8 5660.5 5696.5 5584.3 5595.5 5596.4 5604.3 5652.3 5546.3 5549.5 5560.2 5560.3 5572.9 5376.2 5430.9 5525.8 5535.9 5541.3 5389.8 5422.8 5441.8 5442.0 5450.2 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - PROCESS | 9060 | [#5] yui.html Cycles:180 Average:21589.02 Median:4531.33 stddev:31830.71 (702.5%) stddev-sans-first:31881.54 21:24:42 INFO - PROCESS | 9060 | Values: 748.9 788.9 804.1 813.6 836.8 14499.7 15443.2 15516.3 15536.8 16182.1 15671.9 15954.9 15993.8 15995.8 16027.0 658.5 812.2 834.3 837.7 838.4 804.9 837.5 837.6 839.8 841.6 867.1 868.9 869.3 869.4 869.5 696.8 865.7 866.0 866.1 866.1 605.6 628.2 630.9 632.1 632.2 15289.0 15319.9 15324.7 15339.5 15344.4 13674.7 13703.7 13770.3 13838.7 14586.4 11408.9 11465.0 11564.5 12634.8 12643.9 4539.2 4553.4 4567.7 4789.1 4792.5 67916.0 70470.6 71883.0 78950.6 79675.0 11359.4 11545.5 11838.8 11890.9 12099.9 22203.3 22608.2 22642.2 22650.4 22659.8 8644.6 8789.9 8929.4 9225.4 9265.5 4771.9 4800.3 4823.2 4832.8 4833.9 3553.2 3724.4 3727.8 3730.9 3734.0 607.9 612.9 639.4 639.5 642.9 548.0 584.9 595.0 625.7 683.2 9093.5 9736.5 9889.6 9958.5 10404.8 597.5 659.7 692.8 717.1 732.2 4295.2 4501.6 4523.5 4593.3 4684.2 109920.0 130188.0 131701.3 133533.0 136320.0 105801.0 108841.5 110024.0 113110.4 115439.0 78619.6 79994.2 80041.6 95222.5 98532.0 9527.5 9725.9 9966.0 10193.9 10337.7 51054.0 51384.7 52983.7 54052.0 55198.4 66078.7 66342.3 68507.3 76662.2 77574.0 12950.9 12976.9 13160.4 13394.6 13501.7 15375.5 15430.5 15520.1 15684.8 15747.0 13741.5 14075.5 14182.7 14269.8 14606.6 12646.9 12687.8 12792.7 13238.3 13386.3 14103.2 14270.6 14484.1 14502.7 14533.5 11398.5 11579.8 11736.2 12338.6 12589.9 10928.7 11838.1 11997.7 12295.4 12398.2 21:24:42 INFO - PROCESS | 9060 | -------- Summary: end -------- 21:24:42 INFO - PROCESS | 9060 | 21:24:42 INFO - TEST-INFO | 9060: exit 0 21:24:42 INFO - TEST-OK | dromaeo_css | took 1115836ms 21:24:42 INFO - TEST-START | kraken 21:24:42 INFO - operating with platform_type : linux_ 21:24:42 INFO - Initialising browser for kraken test... 21:24:42 INFO - TEST-INFO | started process 9254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNaSb8/profile http://localhost:38156/getInfo.html) 21:24:46 INFO - PROCESS | 9254 | __metrics Screen width/height:1600/1200 21:24:46 INFO - PROCESS | 9254 | colorDepth:24 21:24:46 INFO - PROCESS | 9254 | Browser inner width/height: 1024/697 21:24:46 INFO - PROCESS | 9254 | __metrics 21:24:47 INFO - PROCESS | 9254 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:24:47 INFO - PROCESS | 9254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:24:48 INFO - TEST-INFO | 9254: exit 0 21:24:48 INFO - Browser initialized. 21:24:48 INFO - Running cycle 1/1 for kraken test... 21:24:48 INFO - TEST-INFO | started process 9335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNaSb8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:25:00 INFO - PROCESS | 9335 | RSS: Main: 155930624 21:25:00 INFO - PROCESS | 9335 | 21:25:02 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-beat-detection) 21:25:03 INFO - PROCESS | 9335 | RSS: Main: 211517440 21:25:03 INFO - PROCESS | 9335 | 21:25:06 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-dft) 21:25:06 INFO - PROCESS | 9335 | RSS: Main: 191586304 21:25:06 INFO - PROCESS | 9335 | 21:25:09 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-fft) 21:25:10 INFO - PROCESS | 9335 | RSS: Main: 270090240 21:25:10 INFO - PROCESS | 9335 | 21:25:12 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38156/tests/kraken/driver.html?testName=audio-oscillator) 21:25:13 INFO - PROCESS | 9335 | RSS: Main: 178532352 21:25:13 INFO - PROCESS | 9335 | 21:25:15 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:25:15 INFO - PROCESS | 9335 | RSS: Main: 183402496 21:25:15 INFO - PROCESS | 9335 | 21:25:18 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38156/tests/kraken/driver.html?testName=imaging-darkroom) 21:25:19 INFO - PROCESS | 9335 | RSS: Main: 218198016 21:25:19 INFO - PROCESS | 9335 | 21:25:22 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38156/tests/kraken/driver.html?testName=imaging-desaturate) 21:25:23 INFO - PROCESS | 9335 | RSS: Main: 218992640 21:25:23 INFO - PROCESS | 9335 | 21:25:26 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38156/tests/kraken/driver.html?testName=json-parse-financial) 21:25:26 INFO - PROCESS | 9335 | RSS: Main: 218034176 21:25:26 INFO - PROCESS | 9335 | 21:25:28 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:25:28 INFO - PROCESS | 9335 | RSS: Main: 182177792 21:25:28 INFO - PROCESS | 9335 | 21:25:30 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:25:31 INFO - PROCESS | 9335 | RSS: Main: 188125184 21:25:31 INFO - PROCESS | 9335 | 21:25:33 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:25:33 INFO - PROCESS | 9335 | RSS: Main: 193732608 21:25:33 INFO - PROCESS | 9335 | 21:25:36 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:25:36 INFO - PROCESS | 9335 | RSS: Main: 188383232 21:25:36 INFO - PROCESS | 9335 | 21:25:39 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:25:40 INFO - PROCESS | 9335 | RSS: Main: 191803392 21:25:40 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | Cycle 1(1): loaded http://localhost:38156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38156/tests/kraken/driver.html?testName=ai-astar) 21:25:42 INFO - PROCESS | 9335 | RSS: Main: 242376704 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | __start_tp_report 21:25:42 INFO - PROCESS | 9335 | _x_x_mozilla_page_load 21:25:42 INFO - PROCESS | 9335 | _x_x_mozilla_page_load_details 21:25:42 INFO - PROCESS | 9335 | |i|pagename|runs| 21:25:42 INFO - PROCESS | 9335 | |0;ai-astar;102;112;113;111;112;113;110;112;112;110 21:25:42 INFO - PROCESS | 9335 | |1;audio-beat-detection;143;118;123;177;117;119;146;118;153;119 21:25:42 INFO - PROCESS | 9335 | |2;audio-dft;160;159;160;160;163;160;163;161;160;162 21:25:42 INFO - PROCESS | 9335 | |3;audio-fft;76;75;76;106;75;77;74;75;103;74 21:25:42 INFO - PROCESS | 9335 | |4;audio-oscillator;83;85;130;84;84;84;126;84;83;133 21:25:42 INFO - PROCESS | 9335 | |5;imaging-gaussian-blur;117;116;117;116;116;116;117;116;116;117 21:25:42 INFO - PROCESS | 9335 | |6;imaging-darkroom;145;146;145;146;146;147;145;146;146;146 21:25:42 INFO - PROCESS | 9335 | |7;imaging-desaturate;96;94;95;94;94;95;94;95;95;95 21:25:42 INFO - PROCESS | 9335 | |8;json-parse-financial;54;50;53;49;50;52;50;51;50;49 21:25:42 INFO - PROCESS | 9335 | |9;json-stringify-tinderbox;63;61;61;62;62;61;62;62;62;63 21:25:42 INFO - PROCESS | 9335 | |10;stanford-crypto-aes;81;78;82;78;77;76;75;79;75;76 21:25:42 INFO - PROCESS | 9335 | |11;stanford-crypto-ccm;137;129;119;129;121;120;128;128;138;127 21:25:42 INFO - PROCESS | 9335 | |12;stanford-crypto-pbkdf2;160;163;158;165;166;173;166;163;162;159 21:25:42 INFO - PROCESS | 9335 | |13;stanford-crypto-sha256-iterative;63;63;64;67;64;63;62;62;63;65 21:25:42 INFO - PROCESS | 9335 | __end_tp_report 21:25:42 INFO - PROCESS | 9335 | __start_cc_report 21:25:42 INFO - PROCESS | 9335 | _x_x_mozilla_cycle_collect,1665 21:25:42 INFO - PROCESS | 9335 | __end_cc_report 21:25:42 INFO - PROCESS | 9335 | __startTimestamp1470889542258__endTimestamp 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | ------- Summary: start ------- 21:25:42 INFO - PROCESS | 9335 | Number of tests: 14 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.23 (2.9%) stddev-sans-first:1.12 21:25:42 INFO - PROCESS | 9335 | Values: 102.0 112.0 113.0 111.0 112.0 113.0 110.0 112.0 112.0 110.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:133.00 stddev:20.55 (15.5%) stddev-sans-first:21.50 21:25:42 INFO - PROCESS | 9335 | Values: 143.0 118.0 123.0 177.0 117.0 119.0 146.0 118.0 153.0 119.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#2] audio-dft Cycles:10 Average:160.80 Median:160.50 stddev:1.40 (0.9%) stddev-sans-first:1.45 21:25:42 INFO - PROCESS | 9335 | Values: 160.0 159.0 160.0 160.0 163.0 160.0 163.0 161.0 160.0 162.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:12.39 (16.5%) stddev-sans-first:13.00 21:25:42 INFO - PROCESS | 9335 | Values: 76.0 75.0 76.0 106.0 75.0 77.0 74.0 75.0 103.0 74.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:84.00 stddev:22.20 (26.4%) stddev-sans-first:22.91 21:25:42 INFO - PROCESS | 9335 | Values: 83.0 85.0 130.0 84.0 84.0 84.0 126.0 84.0 83.0 133.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 21:25:42 INFO - PROCESS | 9335 | Values: 117.0 116.0 117.0 116.0 116.0 116.0 117.0 116.0 116.0 117.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 21:25:42 INFO - PROCESS | 9335 | Values: 145.0 146.0 145.0 146.0 146.0 147.0 145.0 146.0 146.0 146.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.53 21:25:42 INFO - PROCESS | 9335 | Values: 96.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 95.0 95.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:50.50 stddev:1.69 (3.3%) stddev-sans-first:1.33 21:25:42 INFO - PROCESS | 9335 | Values: 54.0 50.0 53.0 49.0 50.0 52.0 50.0 51.0 50.0 49.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 21:25:42 INFO - PROCESS | 9335 | Values: 63.0 61.0 61.0 62.0 62.0 61.0 62.0 62.0 62.0 63.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:78.00 stddev:2.41 (3.1%) stddev-sans-first:2.24 21:25:42 INFO - PROCESS | 9335 | Values: 81.0 78.0 82.0 78.0 77.0 76.0 75.0 79.0 75.0 76.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:128.50 stddev:6.47 (5.0%) stddev-sans-first:5.90 21:25:42 INFO - PROCESS | 9335 | Values: 137.0 129.0 119.0 129.0 121.0 120.0 128.0 128.0 138.0 127.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:164.00 stddev:4.35 (2.7%) stddev-sans-first:4.43 21:25:42 INFO - PROCESS | 9335 | Values: 160.0 163.0 158.0 165.0 166.0 173.0 166.0 163.0 162.0 159.0 21:25:42 INFO - PROCESS | 9335 | 21:25:42 INFO - PROCESS | 9335 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:63.50 stddev:1.51 (2.4%) stddev-sans-first:1.58 21:25:42 INFO - PROCESS | 9335 | Values: 63.0 63.0 64.0 67.0 64.0 63.0 62.0 62.0 63.0 65.0 21:25:42 INFO - PROCESS | 9335 | -------- Summary: end -------- 21:25:42 INFO - PROCESS | 9335 | 21:25:43 INFO - TEST-INFO | 9335: exit 0 21:25:43 INFO - TEST-OK | kraken | took 60384ms 21:25:43 INFO - SUITE-END | took 1176s 21:25:43 INFO - Completed test suite (00:19:36) 21:25:43 INFO - javascript benchmark 21:25:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3077.107638784752, 3093.8453077346126, 3115.43922094023, 3116.454620441423, 3132.9530057049133, 5277.445868183843, 5334.839954801358, 5356.223347614594, 5382.569394448445, 5438.265834112393, 5274.789008439668, 5290.153575427932, 5329.813456529013, 5352.2239275305055, 5376.354837419505, 4752.12085764133, 4838.782254798542, 4846.660733748639, 4911.705297682151, 5098.056859481007, 5032.57223136032, 5050.520200580939, 5060.266261392096, 5096.719680417576, 5102.617303702237, 4757.26262429324, 4833.202521583935, 4851.611871050308, 4878.707277563322, 4926.408830940292, 4780.115678599435, 4798.784054717511, 4805.975970120162, 4807.735574543399, 4809.6392770542, 4767.833125840579, 4805.735684537349, 4844.5155484451625, 4885.975570122162, 4903.975480122576, 5304.92042619361, 5327.200919862013, 5336.573074154058, 5340.599455040888, 5378.0857254266475, 5761.0, 5861.794837180721, 5861.85345366365, 5865.85335366615, 5866.648001119904, 5853.385394533555, 5910.320313163998, 5911.970440147772, 5922.437368449991, 5923.822285331447, 5230.189320655304, 5320.787168513293, 5366.302380690563, 5489.917651235235, 5496.31296087989, 5900.557458190653, 5907.23205983228, 5940.940590594038, 5957.4042595740075, 5960.433758792908, 5657.57568182388, 5663.235463212496, 5667.546596272288, 5714.171445140514, 5716.171155182476, 5650.519705825051, 5727.742251598688, 5768.682722450221, 5792.9420705793245, 5793.275840519935, 5709.172170035322, 5711.143328500758, 5714.885702285931, 5750.1949727038245, 5764.048931926185, 5680.488756011979, 5720.971395142998, 5723.77104915807, 5725.541956643458, 5734.111212762028, 4312.353147027971, 4371.0, 4393.538678438782, 4402.097569998143, 4410.051838854662, 3943.723939324277, 3956.584558621303, 3974.026363540951, 4023.9195216095513, 4037.7779222142476, 5450.127979523258, 5456.91814622781, 5462.344518657707, 5471.644343117685, 5518.034343989786, 5644.266245388073, 5756.740946657411, 5790.0, 5815.82552523425, 5817.88364232713, 5482.671039737549, 5536.252605898233, 5541.972290138524, 5586.441355864462, 5595.7481913313995, 5078.415982162099, 5078.97460512695, 5085.338905942203, 5086.821961231339, 5143.434222235551, 5850.180974663465, 6008.8798224036145, 6089.1779609753, 6140.048292514667, 6144.201253836973, 5768.192453056491, 5852.268466441695, 5854.941450585439, 5898.262717160355, 5905.025670764448, 6075.574709770273, 6091.390860914051, 6094.9085763714465, 6096.786612468542, 6097.146399503984, 5553.389127196086, 5557.3331200256225, 5672.347680016685, 5738.254026976467, 5744.3681195067675, 5677.3754886963225, 5775.94224057754, 5776.191333213437, 5810.4189581041555, 5823.563232757476, 5602.971985140048, 5644.125160600113, 5644.6613203207935, 5657.094864821609, 5658.094704847204, 5437.02133615953, 5520.66875987442, 5616.326040875121, 5626.212330273846, 5630.549556035589, 5467.535259502828, 5595.412481689364, 5631.408702086222, 5641.0, 5648.5763567731765, 5666.263385759825, 5668.404817494104, 5671.546276297804, 5718.942810571841, 5725.6850873202375, 5370.838874833761, 5374.570034397317, 5553.694546799965, 5559.63862348946, 5561.694106824163], "value": 5325.973485671455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.6207227374479, 608.3460280198786, 612.95709300349, 616.9568130230883, 640.5900223856729, 43244.35133473, 44378.11243775127, 44598.0, 48109.27836082462, 48282.27576586345, 46263.0, 46665.533344666546, 46669.29996050062, 46756.29865552019, 46766.766166169204, 7963.0046244219475, 8965.237954773853, 9110.225630821387, 9111.589978450953, 10804.351738895652, 10555.841662375033, 11003.61487347939, 11012.063974562172, 11012.119030477583, 11012.339259644406, 11006.889931100666, 11048.281861678965, 11058.336499809997, 11072.723181920435, 11074.169437292196, 10959.232853700245, 11007.0, 11020.724481887937, 11030.338179709242, 11036.006759391694, 10776.78446431067, 10882.945585272102, 10883.074938630227, 10888.128949684005, 10889.782204355948, 42493.0, 42903.0, 42911.785441072905, 42919.0, 42969.57030429718, 3500.142465096042, 3623.3115708015657, 3624.1302087499075, 3633.1280492681567, 3905.394663827083, 33317.83341083303, 33422.665773342196, 33997.0, 34088.48867266993, 35103.0, 4321.7406955582765, 4438.911221775546, 4454.821807127711, 4564.155631208204, 4587.472440669332, 365980.0, 367445.0, 368389.0, 368532.9999999973, 368803.0, 58736.41263587395, 58928.41071589229, 58969.705151473965, 59156.40843591595, 59235.0, 61088.38911610916, 61099.0, 61142.69428652917, 61162.0, 61187.694061530296, 14483.131012139305, 14484.782728259084, 14527.273636318141, 14535.345909434101, 14552.49066282675, 12282.38588070593, 13017.869821301856, 13397.0, 13399.799003014963, 13469.797953030713, 16334.18329083541, 16347.918260408622, 16474.83525164757, 16561.585960350847, 16680.416185433452, 6092.7906639823195, 6178.196834411498, 6273.811785646438, 6283.403076707706, 6306.684665766693, 976.82417164911, 1079.4548752879746, 1087.407362987158, 1120.2270433400927, 1195.0738177912015, 32868.01395958125, 33292.0, 33293.0, 33293.50059749106, 33756.83121584426, 1078.2883297023927, 1146.4497041420166, 1153.8038533449335, 1167.6146871532374, 1259.1878238536276, 16506.83493165077, 17265.705072119643, 17324.0, 17389.739153912702, 17629.735553966446, 515592.0, 519987.0, 520572.0, 520848.0, 522180.0, 52370.73814630903, 52373.7381313091, 52787.73606132022, 52807.735961319944, 52847.0, 1195.36048214205, 1325.827642406481, 1329.654289884637, 1359.7348517039081, 1368.890488760877, 32853.83573082119, 34039.0, 34569.65430345664, 34735.0, 35174.824125879204, 50122.0, 50612.493875060776, 50704.746476267384, 50717.49282507275, 50826.0, 421184.0, 422826.0, 424054.0, 424339.0, 424595.0, 27629.861850690617, 28698.13905582836, 28761.856190718914, 29706.405871882875, 29979.400411992076, 48546.51453485561, 48899.26651100165, 49214.261786073956, 50094.24858627051, 50643.0, 40098.59901400949, 41074.383884241164, 41706.79146604247, 42255.7887210562, 44087.77956110199, 41379.58620413757, 41814.372784408864, 41875.7906210467, 42804.78597606992, 42906.78546607372, 40764.38853417142, 41868.581314187686, 41926.79036604797, 42612.360814587184, 45822.31266531072, 33500.32999340048, 37996.81001594974, 38024.80987595044, 38482.830852098676, 38927.41608875926, 27986.361772969383, 28297.57553636656, 28800.711992879802, 28900.554972250884, 29183.0], "value": 20703.749048318998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.11847159175954, 398.64520576686755, 400.507375927609, 408.83646541383445, 422.8667969589579, 14033.0, 18656.440306790784, 19794.70307945382, 20115.0, 20154.294599689012, 12473.563425280117, 13171.143875648077, 13207.801882971764, 13278.867211327908, 13304.0, 4050.736702114353, 5415.512603865652, 5561.4716601922755, 5578.386377498472, 5583.497485226349, 4206.600372964564, 4275.144971005796, 4275.593818587229, 5781.971090144543, 6052.122442245872, 6047.244094488189, 6079.270487541501, 6106.511479081662, 6109.663968481732, 6630.801075967729, 6610.801675949705, 6711.026901099338, 6719.563228390139, 6726.764563240312, 6746.055552222692, 5941.55438342126, 5953.642781433128, 6032.733126043536, 6064.06007068905, 6069.393060693939, 14594.270286485633, 14726.631834204123, 14849.331780069922, 20663.760174389583, 23808.880955595283, 2318.1654604342425, 2411.3730430088126, 2478.1945867593104, 2675.973240267592, 3011.0, 14303.642408939755, 14452.78320825178, 14970.47603333878, 15211.163386013764, 15659.13874736889, 1986.960260794776, 1999.2402886902885, 2085.979140208594, 2088.989555052215, 2115.6932244824416, 83757.16242837555, 83816.0, 84038.0, 85737.57131214367, 86531.0, 11161.609343673095, 11528.0, 11592.826107608393, 11597.826032609517, 11604.30374177552, 15915.602109947113, 16013.0, 16097.436589719538, 16120.274587643584, 16157.515274541782, 8272.33821294307, 8374.623141958626, 8492.617832197566, 8559.871601925975, 8596.269317108116, 8073.354131669453, 8171.673133074611, 8219.383546234056, 8318.918540589684, 8400.957995210107, 4835.323054772335, 4897.706137631753, 4946.233333833262, 4990.90018199634, 5031.7987280509105, 2017.939461816148, 2031.3905828251466, 2065.3390914907086, 2101.5271563898305, 2125.691774692661, 917.8898532176181, 1007.2596641468515, 1045.3309881675734, 1052.2844465763355, 1066.648006157966, 19142.61714765697, 19516.219351226075, 20151.697724534144, 20204.898975505028, 20824.47938801512, 955.8709574207393, 957.7749228931195, 979.0, 982.415462799633, 1013.0123129948241, 10548.208884333706, 11125.276857004268, 11146.386948717736, 11180.575242585632, 11269.774604508028, 466380.0, 472365.0, 479540.0, 492924.0, 494185.0, 90420.0, 90827.54586227027, 92281.0, 92434.53782731178, 92923.53538232266, 973.445136272318, 980.8970058143792, 991.0832479956011, 995.0149352141473, 997.9501024948723, 16042.598935026717, 16884.831151688326, 17515.649687005935, 17836.910815446357, 18087.728684070014, 42073.36889946715, 42465.36301955411, 42782.78608607061, 43234.0, 44040.55959440493, 57460.71269643625, 59016.704916475144, 59163.0, 59807.401925981925, 60538.39461605327, 8237.299829514559, 8244.505329680238, 8570.614322355385, 8609.526476043879, 8650.717894642192, 12449.93775031119, 13025.348732563523, 13101.475940962255, 13191.0, 13381.933090334485, 11417.37204453763, 11633.825492617787, 11698.415079246173, 12216.633500994983, 12227.0829687772, 8272.214139656722, 11287.830682539932, 11305.208635395444, 11428.771424571296, 11690.123240756808, 11207.775844483227, 11471.197016208785, 11485.598004069818, 12026.27842329463, 12101.636950891487, 8617.95691021566, 8770.649174032967, 8778.34162437807, 8789.945206575252, 8925.866112008454, 10378.740531486774, 11914.10644201671, 11940.880591193976, 11994.76010479768, 11998.700032499257], "value": 9286.06122559442, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.687288487206, 1455.6506438454771, 1518.2105305241275, 1636.7381219004963, 1785.4554360919922, 8413.663453461862, 8824.0, 8843.557822108893, 9071.365004449692, 9087.227585655228, 8925.687600933968, 9014.774630634238, 9024.81950360994, 9033.277337812975, 9038.72883813485, 2428.8785560721935, 2448.816338774599, 2454.349597356693, 2454.619533972237, 2455.84037037592, 2323.082382458928, 2336.918207862725, 2341.075275266269, 2342.16853017179, 2392.963839766904, 2383.8688872112025, 2384.5350156719446, 2386.725526564441, 2414.239514552919, 2419.9516009679796, 2316.5366926614743, 2345.9648105278434, 2348.765123487655, 2353.564590550745, 2372.893219805113, 2336.5210131922913, 2356.8821558921986, 2364.9527009459716, 2367.0886708617136, 2369.988150059256, 8920.063393343671, 8958.148975847284, 9007.369484136114, 9060.275177985744, 9069.863952040725, 5292.259083728281, 5516.9448305516835, 5526.723663816793, 5529.115341545334, 5545.667259964415, 3438.6561343865665, 3467.9479807802904, 3512.59605145409, 3519.04985653872, 3524.806135662563, 1147.1912301827192, 1150.4075401168404, 1163.2438914705424, 1171.707073231692, 1180.586794621884, 12525.123241373107, 12556.99544036484, 12594.496220151183, 12665.176763510342, 12676.492940282378, 4489.842855500042, 4663.183942809995, 4760.262159365304, 4778.354922085544, 4780.617550595944, 9946.154576861049, 9978.750531236632, 9997.500124993721, 10006.149477294366, 10013.64952226683, 5381.327334083239, 5392.137258038773, 5400.405955344909, 5400.540954018874, 5402.081646120169, 4155.854545090907, 4415.4259946206785, 4536.933820552167, 4929.088118698052, 5051.267566202953, 3863.3046051710962, 3864.8840534784003, 3957.248122856648, 3963.088489647397, 3976.443297938291, 1151.654503648902, 1167.1888037813808, 1173.0263880978766, 1177.7821103095955, 1182.7338848758961, 852.411835833273, 936.8969413364524, 964.064857088623, 972.547765289144, 988.8912219655832, 6073.544484163617, 6116.60242084272, 6220.098085777537, 6322.0, 6345.555811093179, 471.7476150259595, 471.92685133804315, 473.9502352253033, 474.3027749208658, 475.11628371229483, 4098.262312783728, 4140.192662430857, 4140.4617399738445, 4166.229247589205, 4179.791010449465, 10946.233763636468, 11470.0, 11477.196596238351, 11482.77034459323, 11550.0, 11362.034227090624, 11392.088632909348, 11392.658220253406, 11393.0, 11397.943010285062, 7969.641366138538, 8188.181181881883, 8248.711295104762, 8456.830863382698, 8477.321814254969, 6038.909416358671, 6060.272767267956, 6250.312465628686, 6295.905561416674, 6314.358842086533, 10405.791884162425, 10509.474526273503, 10577.9471102644, 10708.196885233483, 10714.089302409382, 9433.386829856037, 10440.0, 10518.211134164816, 10682.89317106819, 10774.515146818256, 7823.413244006609, 8021.679132834622, 8046.114927358103, 8237.217464340878, 8275.668973241003, 8894.777630559287, 8897.332700047651, 8911.06433824465, 8923.241524470493, 8995.505247211204, 7797.298243158143, 7815.335696465637, 7839.84320313602, 7897.973263475711, 8029.5583742894705, 7830.412719046209, 7905.130435652189, 7935.0, 7955.04539455246, 8021.759347219593, 7303.8539229216185, 7311.086114235721, 7357.227491113356, 7357.484976051625, 7366.447516436181, 5268.0, 5296.285001524745, 5403.0814761489855, 5418.160185171305, 5425.511703946664, 5428.891422171613, 5481.177193745145, 5492.615516913777, 5606.411326810626, 5637.8590535236945], "value": 4647.9148158646285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.36136978371303, 337.7770671356905, 342.5102103991293, 344.6019847076627, 348.5190437196668, 5318.175682769167, 5340.813071542497, 5372.382176049756, 5380.461953804617, 5402.78388864446, 5269.394019687737, 5300.946990530094, 5317.564257650432, 5337.01265265926, 5346.705931173784, 4025.29557327468, 4042.454268673721, 4140.482439695038, 4183.933097060245, 4206.642435392995, 4173.977375542996, 4180.3729440584, 4181.163767246548, 4205.600467955539, 4206.8737937861915, 4149.439825623533, 4156.584341565849, 4168.958310416887, 4173.353130264799, 4188.455500784894, 3946.9605303947023, 4011.5587285398583, 4142.79286035697, 4166.937495937564, 4182.2890108681295, 3695.4826324314618, 3706.962930370689, 3715.38380804351, 3717.7955212463303, 3719.758215715997, 5161.561267292284, 5175.430702622746, 5183.377994640629, 5213.895722085537, 5220.504052115043, 4498.707584007062, 4724.95275047252, 4733.290006499018, 4750.524947505256, 4776.044791041771, 5454.281901201109, 5500.367457742371, 5522.502974732293, 5523.475269849358, 5536.363318218416, 3994.1612261425134, 3994.9201015979806, 4007.7595344279434, 4018.618231268054, 4021.0, 5534.640248383842, 5708.628939118944, 5720.971395143039, 5742.425757424266, 5775.191473193756, 4073.45008423859, 4077.2864748668862, 4090.4886889138857, 4098.663761655827, 4100.876973690794, 4143.7306575072525, 4149.668026557868, 4151.398047283187, 4155.877912963483, 4156.189543039079, 4573.474050484237, 4710.717356958594, 4723.6929599575915, 4747.240441529339, 4752.2158843790385, 5499.285092937893, 5521.061419558685, 5525.673838278864, 5543.752655652446, 5621.353544342453, 1407.9862499000703, 1431.6206205355381, 1435.5980325508874, 1440.6038339456734, 1440.7118576284786, 867.3624885708999, 919.94940278284, 925.2181906289175, 937.3906960475609, 939.8026414452904, 5238.711870847063, 5335.813246536352, 5343.893122137613, 5345.663584103974, 5362.383325917492, 5649.06790379591, 5650.0, 5657.415923541415, 5660.462256085665, 5675.801346952837, 5102.260172275, 5311.336082989626, 5339.946600533945, 5344.652597581223, 5365.356157261153, 2968.4656761783085, 3010.3527741535677, 3017.698230176965, 3021.6676165621766, 3027.455058089521, 5933.881322373615, 5936.5547583931375, 5950.345461999176, 5962.552808539377, 5971.14894382734, 5587.38538760736, 5766.913496297559, 5825.4174582542255, 5828.708564571839, 5832.862591794644, 5918.763249469973, 5924.7926322578505, 5932.911006334908, 5936.673482958478, 5947.643141411356, 5444.482774136451, 5646.802361917313, 5686.43135686428, 5727.59208271078, 5731.971340143273, 5642.971785141212, 5670.404607516316, 5676.5742569308295, 5680.68756218412, 5687.857803554944, 5941.524678025661, 5956.855532008387, 5961.165436838933, 6004.369541198286, 6016.7292471838, 3988.9202215956097, 4095.2833254180996, 4097.160082183085, 4110.609492098365, 4141.254574176677, 5684.77260909559, 5727.828165155052, 5729.427057294237, 5738.512226460798, 5761.934042202206, 5477.616566840283, 5631.887362252814, 5633.8309850704545, 5660.51885589713, 5696.458836410535, 5584.301962254718, 5595.468430499096, 5596.384397716167, 5604.271444712162, 5652.265205523256, 5546.334439867081, 5549.472800083986, 5560.248521715646, 5560.332760068818, 5572.86067848307, 5376.19357096423, 5430.864228394322, 5525.751341189576, 5535.8892822142525, 5541.2796336476, 5389.83830485086, 5422.755975981011, 5441.809536666197, 5441.972790136024, 5450.155225939986], "value": 4254.022979751782, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.8577170337638, 788.9487183333083, 804.1074407407773, 813.5850716134771, 836.799168199632, 14499.710005799878, 15443.227838608052, 15516.30176642051, 15536.766948495782, 16182.109983950879, 15671.92164039181, 15954.920225398857, 15993.760093598576, 15995.760063599055, 16027.0, 658.4830907737403, 812.2446125103666, 834.3367023216557, 837.6816809612338, 838.4424357802054, 804.899387576553, 837.543538771368, 837.5560952695081, 839.8446287436814, 841.5750046226647, 867.0765634599163, 868.9348298877578, 869.3479890082439, 869.42183448007, 869.5261082709907, 696.8432102776885, 865.6623916672503, 865.9653613855438, 866.0603245478632, 866.0862789756804, 605.5791225098558, 628.1864984844592, 630.8706715123388, 632.1466020871832, 632.2413104274889, 15289.0, 15319.923400383039, 15324.693506129926, 15339.539813805492, 15344.386224551115, 13674.658133546742, 13703.725925481534, 13770.311484425738, 13838.654033649138, 14586.416543338253, 11408.942955285254, 11465.0, 11564.53741850325, 12634.81047784284, 12643.873561264454, 4539.182947069559, 4553.408056952575, 4567.703099298568, 4789.090072886141, 4792.496787837262, 67916.0, 70470.64764676195, 71883.0, 78950.60524697397, 79675.0, 11359.393000436996, 11545.538178472767, 11838.763224735458, 11890.940545297219, 12099.879001209874, 22203.333899983027, 22608.17397825272, 22642.207522736626, 22650.43373915665, 22659.773402266095, 8644.610992505352, 8789.868151977726, 8929.374943754005, 9225.446473211628, 9265.490398028038, 4771.904561908743, 4800.255960326155, 4823.204171311764, 4832.758362081881, 4833.879153021203, 3553.200529880808, 3724.4413337999517, 3727.7576957497677, 3730.869419570302, 3734.0, 607.9421806057428, 612.9478994285447, 639.3957709964133, 639.4947991087082, 642.8874946884276, 548.0049350173143, 584.915187297848, 594.9643021418642, 625.7371903800415, 683.2484267306023, 9093.499857507768, 9736.464494452874, 9889.604415823287, 9958.539149755074, 10404.75142982847, 597.5040716205539, 659.650385295794, 692.7817737412698, 717.1430140981581, 732.1847496376424, 4295.162443323522, 4501.639868810553, 4523.479799822997, 4593.333966574828, 4684.1568517666465, 109920.0, 130188.0, 131701.34149329193, 133533.0, 136320.0, 105801.0, 108841.45579272212, 110024.0, 113110.43444782724, 115439.0, 78619.60690196512, 79994.20005799984, 80041.59979200183, 95222.52388738011, 98532.0, 9527.523623818919, 9725.9027409725, 9966.0, 10193.898061019592, 10337.741556460847, 51054.0, 51384.74307628438, 52983.73508132435, 54052.0, 55198.44801552094, 66078.66960665166, 66342.33657663554, 68507.3149268521, 76662.23337766551, 77574.0, 12950.935245323713, 12976.870231297944, 13160.41742205623, 13394.598162055154, 13501.662458438617, 15375.538733838002, 15430.5370838875, 15520.14639194855, 15684.843151568795, 15747.0, 13741.519046833313, 14075.507357242448, 14182.716345673236, 14269.785953210503, 14606.561803145923, 12646.936765316115, 12687.80968285495, 12792.70971228518, 13238.338083096, 13386.26375549354, 14103.224322662352, 14270.571882843531, 14484.130952142905, 14502.709945801236, 14533.491327803476, 11398.487068081791, 11579.768404632028, 11736.178467507534, 12338.568150114703, 12589.93705031469, 10928.672139835817, 11838.112141589243, 11997.700057498632, 12295.446704898122, 12398.194117382342], "value": 7718.065480984471, "unit": "score"}], "name": "dromaeo_css", "value": 7339.199092732562, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 113.0, 111.0, 112.0, 113.0, 110.0, 112.0, 112.0, 110.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 123.0, 177.0, 117.0, 119.0, 146.0, 118.0, 153.0, 119.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 160.0, 163.0, 160.0, 163.0, 161.0, 160.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 106.0, 75.0, 77.0, 74.0, 75.0, 103.0, 74.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 130.0, 84.0, 84.0, 84.0, 126.0, 84.0, 83.0, 133.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 50.0, 53.0, 49.0, 50.0, 52.0, 50.0, 51.0, 50.0, 49.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 63.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 82.0, 78.0, 77.0, 76.0, 75.0, 79.0, 75.0, 76.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 129.0, 119.0, 129.0, 121.0, 120.0, 128.0, 128.0, 138.0, 127.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 158.0, 165.0, 166.0, 173.0, 166.0, 163.0, 162.0, 159.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 67.0, 64.0, 63.0, 62.0, 62.0, 63.0, 65.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1485.5, "alertThreshold": 2.0}]} 21:25:43 INFO - Return code: 0 21:25:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:25:43 INFO - # TBPL SUCCESS # 21:25:43 INFO - Running post-action listener: _resource_record_post_action 21:25:43 INFO - [mozharness: 2016-08-11 04:25:43.542447Z] Finished run-tests step (success) 21:25:43 INFO - Running post-run listener: _resource_record_post_run 21:25:43 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 555044864; Read time: 0; Write time: 482860 21:25:43 INFO - TinderboxPrint: CPU usage
13.0% 21:25:43 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:25:43 INFO - TinderboxPrint: I/O write bytes / time
555,044,864 / 482,860 21:25:43 INFO - TinderboxPrint: CPU idle
8,289.4 (87.0%) 21:25:43 INFO - TinderboxPrint: CPU user
1,195.8 (12.5%) 21:25:43 INFO - TinderboxPrint: Swap in / out
0 / 0 21:25:43 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 260153344; Read time: 0; Write time: 265440 21:25:43 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 294891520; Read time: 0; Write time: 217420 21:25:44 INFO - Running post-run listener: _upload_blobber_files 21:25:44 INFO - Blob upload gear active. 21:25:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:25:44 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 21:25:44 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'] 21:25:44 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 21:25:44 INFO - (blobuploader) - INFO - Open directory for files ... 21:25:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:25:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:25:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:45 INFO - (blobuploader) - INFO - Done attempting. 21:25:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:25:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:25:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:49 INFO - (blobuploader) - INFO - Done attempting. 21:25:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:25:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:25:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:49 INFO - (blobuploader) - INFO - Done attempting. 21:25:49 INFO - (blobuploader) - INFO - Iteration through files over. 21:25:49 INFO - Return code: 0 21:25:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:25:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:25:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a0ff1cd69d77b8eef34d30a19d574f9fde169a4e2567f829533d0765bda8f22c3ec33e712fbb2db33b6c22cb8e834ebc27ff0daa7c849d2ebedbb2521a60fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/811726c59d7474758ed620307d7a10c69c88926c1fe6605364c637083a3be134406d9484b16ed02a78ef5cb532d8d737c5914df2db5b4f136500e108119673de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960730606e9adb94f6cd21976cde7a98a823cd83c0c03ffe51f7cebb0a4faf56b163bf1e7923f8f6bddbd19dc7944f4c410a9e2ec7e2c6465f0750c64d4af654"} 21:25:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:25:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:25:49 INFO - Contents: 21:25:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a0ff1cd69d77b8eef34d30a19d574f9fde169a4e2567f829533d0765bda8f22c3ec33e712fbb2db33b6c22cb8e834ebc27ff0daa7c849d2ebedbb2521a60fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/811726c59d7474758ed620307d7a10c69c88926c1fe6605364c637083a3be134406d9484b16ed02a78ef5cb532d8d737c5914df2db5b4f136500e108119673de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960730606e9adb94f6cd21976cde7a98a823cd83c0c03ffe51f7cebb0a4faf56b163bf1e7923f8f6bddbd19dc7944f4c410a9e2ec7e2c6465f0750c64d4af654"} 21:25:49 INFO - Running post-run listener: copy_logs_to_upload_dir 21:25:49 INFO - Copying logs to upload dir... 21:25:49 INFO - mkdir: /builds/slave/test/build/upload/logs 21:25:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.466324 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-08-10 21:25:49.956167) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 21:25:49.960128) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1470888079.739826-211675254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a0ff1cd69d77b8eef34d30a19d574f9fde169a4e2567f829533d0765bda8f22c3ec33e712fbb2db33b6c22cb8e834ebc27ff0daa7c849d2ebedbb2521a60fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/811726c59d7474758ed620307d7a10c69c88926c1fe6605364c637083a3be134406d9484b16ed02a78ef5cb532d8d737c5914df2db5b4f136500e108119673de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960730606e9adb94f6cd21976cde7a98a823cd83c0c03ffe51f7cebb0a4faf56b163bf1e7923f8f6bddbd19dc7944f4c410a9e2ec7e2c6465f0750c64d4af654"} build_url:https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011272 build_url: 'https://queue.taskcluster.net/v1/task/ZI_QqzTGRyCqDpGX64Gnjw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a0ff1cd69d77b8eef34d30a19d574f9fde169a4e2567f829533d0765bda8f22c3ec33e712fbb2db33b6c22cb8e834ebc27ff0daa7c849d2ebedbb2521a60fd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/811726c59d7474758ed620307d7a10c69c88926c1fe6605364c637083a3be134406d9484b16ed02a78ef5cb532d8d737c5914df2db5b4f136500e108119673de", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960730606e9adb94f6cd21976cde7a98a823cd83c0c03ffe51f7cebb0a4faf56b163bf1e7923f8f6bddbd19dc7944f4c410a9e2ec7e2c6465f0750c64d4af654"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-10 21:25:49.987264) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:25:49.987643) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=923d835db2ba3686ebe126b700000192-1470888079.739826-211675254 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-10 21:25:50.071836) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 21:25:50.072267) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-10 21:25:50.072649) ========= ========= Total master_lag: 0.19 =========