builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-051 starttime: 1470612470.9 results: success (0) buildid: 20160807150052 builduid: 6fc9bd8f35e4477f90b33128110ce38e revision: 2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:50.900638) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:50.901059) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:50.901403) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:50.970087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:50.970351) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-07 16:27:52-- 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% 223M=0s 2016-08-07 16:27:52 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112872 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:51.097244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:51.097536) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022790 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:51.148083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:51.148479) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0 --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 2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0 --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=5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-07 16:27:52,491 truncating revision to first 12 chars 2016-08-07 16:27:52,491 Setting DEBUG logging. 2016-08-07 16:27:52,491 attempt 1/10 2016-08-07 16:27:52,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c1f6bf57d21?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 16:27:53,126 unpacking tar archive at: mozilla-central-2c1f6bf57d21/testing/mozharness/ program finished with exit code 0 elapsedTime=0.982995 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:52.144902) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:52.145212) ========= script_repo_revision: 2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:52.145590) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:52.145843) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 16:27:52.159933) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-08-07 16:27:52.160233) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:27:53 INFO - MultiFileLogger online at 20160807 16:27:53 in /builds/slave/test 16:27:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:27:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:27:53 INFO - {'append_to_log': False, 16:27:53 INFO - 'base_work_dir': '/builds/slave/test', 16:27:53 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:27:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:27:53 INFO - 'branch': 'Firefox-Non-PGO', 16:27:53 INFO - 'buildbot_json_path': 'buildprops.json', 16:27:53 INFO - 'config_files': ('talos/linux_config.py',), 16:27:53 INFO - 'default_actions': ('clobber', 16:27:53 INFO - 'read-buildbot-config', 16:27:53 INFO - 'download-and-extract', 16:27:53 INFO - 'populate-webroot', 16:27:53 INFO - 'create-virtualenv', 16:27:53 INFO - 'install', 16:27:53 INFO - 'run-tests'), 16:27:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:27:53 INFO - 'download_minidump_stackwalk': True, 16:27:53 INFO - 'download_symbols': 'ondemand', 16:27:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:27:53 INFO - 'tooltool.py': '/tools/tooltool.py', 16:27:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:27:53 INFO - '/tools/misc-python/virtualenv.py')}, 16:27:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:27:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:27:53 INFO - 'installer_path': 'installer.exe', 16:27:53 INFO - 'log_level': 'info', 16:27:53 INFO - 'log_name': 'talos', 16:27:53 INFO - 'log_to_console': True, 16:27:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:27:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:27:53 INFO - 'opt_config_files': (), 16:27:53 INFO - 'pip_index': False, 16:27:53 INFO - 'sps_profile': False, 16:27:53 INFO - 'sps_profile_interval': 0, 16:27:53 INFO - 'suite': 'dromaeojs', 16:27:53 INFO - 'system_bits': '32', 16:27:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:27:53 INFO - 'title': 'talos-linux64-ix-051', 16:27:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:27:53 INFO - 'use_talos_json': True, 16:27:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:27:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:27:53 INFO - 'work_dir': 'build'} 16:27:53 INFO - [mozharness: 2016-08-07 23:27:53.597934Z] Running clobber step. 16:27:53 INFO - Running pre-action listener: _resource_record_pre_action 16:27:53 INFO - Running main action method: clobber 16:27:53 INFO - rmtree: /builds/slave/test/build 16:27:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:27:54 INFO - Running post-action listener: _resource_record_post_action 16:27:54 INFO - [mozharness: 2016-08-07 23:27:54.059928Z] Finished clobber step (success) 16:27:54 INFO - [mozharness: 2016-08-07 23:27:54.060029Z] Running read-buildbot-config step. 16:27:54 INFO - Running pre-action listener: _resource_record_pre_action 16:27:54 INFO - Running main action method: read_buildbot_config 16:27:54 INFO - Using buildbot properties: 16:27:54 INFO - { 16:27:54 INFO - "project": "", 16:27:54 INFO - "product": "firefox", 16:27:54 INFO - "script_repo_revision": "production", 16:27:54 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:27:54 INFO - "repository": "", 16:27:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:27:54 INFO - "buildid": "20160807150052", 16:27:54 INFO - "pgo_build": "False", 16:27:54 INFO - "basedir": "/builds/slave/test", 16:27:54 INFO - "buildnumber": 588, 16:27:54 INFO - "slavename": "talos-linux64-ix-051", 16:27:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:27:54 INFO - "platform": "ubuntu64_hw", 16:27:54 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:27:54 INFO - "branch": "mozilla-central", 16:27:54 INFO - "repo_path": "mozilla-central", 16:27:54 INFO - "revision": "2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0", 16:27:54 INFO - "stage_platform": "linux64", 16:27:54 INFO - "builduid": "6fc9bd8f35e4477f90b33128110ce38e", 16:27:54 INFO - "slavebuilddir": "test" 16:27:54 INFO - } 16:27:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:27:54 INFO - Running post-action listener: _resource_record_post_action 16:27:54 INFO - [mozharness: 2016-08-07 23:27:54.062494Z] Finished read-buildbot-config step (success) 16:27:54 INFO - [mozharness: 2016-08-07 23:27:54.062583Z] Running download-and-extract step. 16:27:54 INFO - Running pre-action listener: _resource_record_pre_action 16:27:54 INFO - Running main action method: download_and_extract 16:27:54 INFO - mkdir: /builds/slave/test/build/tests 16:27:54 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')]} 16:27:54 INFO - trying https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:27:54 INFO - Downloading https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:54 INFO - Downloaded 1361 bytes. 16:27:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:27:54 INFO - Using the following test package requirements: 16:27:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:27:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:27:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:27:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'jsshell-linux-x86_64.zip'], 16:27:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:27:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:27:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:27:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:27:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:27:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:27:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:27:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:27:54 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')]} 16:27:54 INFO - trying https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:27:54 INFO - Downloading https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:55 INFO - Downloaded 20257838 bytes. 16:27:55 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'] 16:27:55 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 16:27:56 INFO - Return code: 0 16:27:56 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 16:27:56 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')]} 16:27:56 INFO - trying https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:27:56 INFO - Downloading https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:57 INFO - Downloaded 20257838 bytes. 16:27:57 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'] 16:27:57 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 16:27:59 INFO - Return code: 0 16:27:59 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')]} 16:27:59 INFO - trying https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:27:59 INFO - Downloading https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:28:00 INFO - Downloaded 14413723 bytes. 16:28:00 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'] 16:28:00 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 16:28:01 INFO - Return code: 0 16:28:01 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')]} 16:28:01 INFO - trying https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:28:01 INFO - Downloading https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:28:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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 16:28:03 INFO - Downloaded 56122595 bytes. 16:28:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:28:03 INFO - mkdir: /builds/slave/test/properties 16:28:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:28:03 INFO - Writing to file /builds/slave/test/properties/build_url 16:28:03 INFO - Contents: 16:28:03 INFO - build_url:https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:28:04 INFO - Running post-action listener: _resource_record_post_action 16:28:04 INFO - Running post-action listener: set_extra_try_arguments 16:28:04 INFO - [mozharness: 2016-08-07 23:28:04.457798Z] Finished download-and-extract step (success) 16:28:04 INFO - [mozharness: 2016-08-07 23:28:04.458030Z] Running populate-webroot step. 16:28:04 INFO - Running pre-action listener: _resource_record_pre_action 16:28:04 INFO - Running main action method: populate_webroot 16:28:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:28:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:28:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:28:04 INFO - '--tppagecycles', 16:28:04 INFO - '7'], 16:28:04 INFO - 'tests': ['tsvgm']}}, 16:28:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:28:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:28:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:28:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:28:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:28:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['damp', 'tps']}, 16:28:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'tests': ['damp', 'tps']}, 16:28:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['dromaeo_dom']}, 16:28:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:28:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['basic_compositor_video']}, 16:28:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:28:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['a11yr', 16:28:04 INFO - 'ts_paint', 16:28:04 INFO - 'tpaint', 16:28:04 INFO - 'sessionrestore', 16:28:04 INFO - 'sessionrestore_no_auto_restore', 16:28:04 INFO - 'tabpaint']}, 16:28:04 INFO - 'other-e10s': {'tests': ['a11yr', 16:28:04 INFO - 'ts_paint', 16:28:04 INFO - 'tpaint', 16:28:04 INFO - 'sessionrestore', 16:28:04 INFO - 'sessionrestore_no_auto_restore', 16:28:04 INFO - 'tabpaint']}, 16:28:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:28:04 INFO - 'ts_paint', 16:28:04 INFO - 'tpaint', 16:28:04 INFO - 'sessionrestore', 16:28:04 INFO - 'sessionrestore_no_auto_restore', 16:28:04 INFO - 'tabpaint']}, 16:28:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:28:04 INFO - 'ts_paint', 16:28:04 INFO - 'tpaint', 16:28:04 INFO - 'sessionrestore', 16:28:04 INFO - 'sessionrestore_no_auto_restore', 16:28:04 INFO - 'tabpaint']}, 16:28:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['a11yr', 16:28:04 INFO - 'ts_paint', 16:28:04 INFO - 'tpaint', 16:28:04 INFO - 'sessionrestore', 16:28:04 INFO - 'sessionrestore_no_auto_restore', 16:28:04 INFO - 'tabpaint']}, 16:28:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['a11yr', 16:28:04 INFO - 'ts_paint', 16:28:04 INFO - 'tpaint', 16:28:04 INFO - 'sessionrestore', 16:28:04 INFO - 'sessionrestore_no_auto_restore', 16:28:04 INFO - 'tabpaint']}, 16:28:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['tsvgx', 16:28:04 INFO - 'tsvgr_opacity', 16:28:04 INFO - 'tart', 16:28:04 INFO - 'tscrollx', 16:28:04 INFO - 'cart']}, 16:28:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:28:04 INFO - 'tsvgr_opacity', 16:28:04 INFO - 'tart', 16:28:04 INFO - 'tscrollx', 16:28:04 INFO - 'cart']}, 16:28:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'talos_options': ['--disable-e10s'], 16:28:04 INFO - 'tests': ['tp5o']}, 16:28:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'tests': ['tp5o']}, 16:28:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'talos_options': ['--disable-e10s', 16:28:04 INFO - '--xperf_path', 16:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:04 INFO - 'tests': ['tp5n']}, 16:28:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:28:04 INFO - 'talos_options': ['--xperf_path', 16:28:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:28:04 INFO - 'tests': ['tp5n']}}, 16:28:04 INFO - 'talos.zip': {'path': '', 16:28:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:28:04 INFO - Running post-action listener: _resource_record_post_action 16:28:04 INFO - [mozharness: 2016-08-07 23:28:04.476021Z] Finished populate-webroot step (success) 16:28:04 INFO - [mozharness: 2016-08-07 23:28:04.476110Z] Running create-virtualenv step. 16:28:04 INFO - Running pre-action listener: _resource_record_pre_action 16:28:04 INFO - Running main action method: create_virtualenv 16:28:04 INFO - Creating virtualenv /builds/slave/test/build/venv 16:28:04 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 16:28:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:28:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:28:04 INFO - Using real prefix '/usr' 16:28:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:28:05 INFO - Installing distribute.............................................................................................................................................................................................done. 16:28:07 INFO - Installing pip.................done. 16:28:07 INFO - Return code: 0 16:28:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:28:07 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')]} 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 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')]} 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:07 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 16:28:07 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 16:28:07 INFO - Using env: {'DISPLAY': ':0', 16:28:07 INFO - 'HOME': '/home/cltbld', 16:28:07 INFO - 'LANG': 'en_US.UTF-8', 16:28:07 INFO - 'LANGUAGE': 'en_US:en', 16:28:07 INFO - 'LOGNAME': 'cltbld', 16:28:07 INFO - 'MAIL': '/var/mail/cltbld', 16:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:07 INFO - 'MOZ_NO_REMOTE': '1', 16:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:07 INFO - 'NO_EM_RESTART': '1', 16:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:07 INFO - 'PWD': '/builds/slave/test', 16:28:07 INFO - 'SHELL': '/bin/bash', 16:28:07 INFO - 'SHLVL': '1', 16:28:07 INFO - 'TERM': 'linux', 16:28:07 INFO - 'TMOUT': '86400', 16:28:07 INFO - 'USER': 'cltbld', 16:28:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:07 INFO - '_': '/tools/buildbot/bin/python'} 16:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:07 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 16:28:07 INFO - Cleaning up... 16:28:07 INFO - Return code: 0 16:28:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:28:07 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')]} 16:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 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')]} 16:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:07 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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:07 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 16:28:07 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 16:28:07 INFO - Using env: {'DISPLAY': ':0', 16:28:07 INFO - 'HOME': '/home/cltbld', 16:28:07 INFO - 'LANG': 'en_US.UTF-8', 16:28:07 INFO - 'LANGUAGE': 'en_US:en', 16:28:07 INFO - 'LOGNAME': 'cltbld', 16:28:07 INFO - 'MAIL': '/var/mail/cltbld', 16:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:07 INFO - 'MOZ_NO_REMOTE': '1', 16:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:07 INFO - 'NO_EM_RESTART': '1', 16:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:07 INFO - 'PWD': '/builds/slave/test', 16:28:07 INFO - 'SHELL': '/bin/bash', 16:28:07 INFO - 'SHLVL': '1', 16:28:07 INFO - 'TERM': 'linux', 16:28:07 INFO - 'TMOUT': '86400', 16:28:07 INFO - 'USER': 'cltbld', 16:28:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:07 INFO - '_': '/tools/buildbot/bin/python'} 16:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:07 INFO - Downloading/unpacking psutil>=3.1.1 16:28:07 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 16:28:07 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 16:28:07 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 16:28:07 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 16:28:07 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 16:28:07 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 16:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:28:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:09 INFO - Installing collected packages: psutil 16:28:09 INFO - Running setup.py install for psutil 16:28:09 INFO - building 'psutil._psutil_linux' extension 16:28:09 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 16:28:09 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 16:28:09 INFO - building 'psutil._psutil_posix' extension 16:28:09 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 16:28:09 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 16:28:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:28:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:28:09 INFO - Successfully installed psutil 16:28:09 INFO - Cleaning up... 16:28:10 INFO - Return code: 0 16:28:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:28:10 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')]} 16:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:10 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')]} 16:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:10 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 16:28:10 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 16:28:10 INFO - Using env: {'DISPLAY': ':0', 16:28:10 INFO - 'HOME': '/home/cltbld', 16:28:10 INFO - 'LANG': 'en_US.UTF-8', 16:28:10 INFO - 'LANGUAGE': 'en_US:en', 16:28:10 INFO - 'LOGNAME': 'cltbld', 16:28:10 INFO - 'MAIL': '/var/mail/cltbld', 16:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:10 INFO - 'MOZ_NO_REMOTE': '1', 16:28:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:10 INFO - 'NO_EM_RESTART': '1', 16:28:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:10 INFO - 'PWD': '/builds/slave/test', 16:28:10 INFO - 'SHELL': '/bin/bash', 16:28:10 INFO - 'SHLVL': '1', 16:28:10 INFO - 'TERM': 'linux', 16:28:10 INFO - 'TMOUT': '86400', 16:28:10 INFO - 'USER': 'cltbld', 16:28:10 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:10 INFO - '_': '/tools/buildbot/bin/python'} 16:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:28:10 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 16:28:10 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 16:28:10 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 16:28:10 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 16:28:10 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 16:28:10 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 16:28:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:28:12 INFO - Installing collected packages: mozsystemmonitor 16:28:12 INFO - Running setup.py install for mozsystemmonitor 16:28:12 INFO - Successfully installed mozsystemmonitor 16:28:12 INFO - Cleaning up... 16:28:12 INFO - Return code: 0 16:28:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:28:12 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')]} 16:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:12 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')]} 16:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:12 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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:12 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 16:28:12 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 16:28:12 INFO - Using env: {'DISPLAY': ':0', 16:28:12 INFO - 'HOME': '/home/cltbld', 16:28:12 INFO - 'LANG': 'en_US.UTF-8', 16:28:12 INFO - 'LANGUAGE': 'en_US:en', 16:28:12 INFO - 'LOGNAME': 'cltbld', 16:28:12 INFO - 'MAIL': '/var/mail/cltbld', 16:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:12 INFO - 'MOZ_NO_REMOTE': '1', 16:28:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:12 INFO - 'NO_EM_RESTART': '1', 16:28:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:12 INFO - 'PWD': '/builds/slave/test', 16:28:12 INFO - 'SHELL': '/bin/bash', 16:28:12 INFO - 'SHLVL': '1', 16:28:12 INFO - 'TERM': 'linux', 16:28:12 INFO - 'TMOUT': '86400', 16:28:12 INFO - 'USER': 'cltbld', 16:28:12 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:12 INFO - '_': '/tools/buildbot/bin/python'} 16:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:12 INFO - Downloading/unpacking jsonschema==2.5.1 16:28:12 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 16:28:12 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 16:28:12 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 16:28:12 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 16:28:12 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 16:28:12 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 16:28:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:28:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:14 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 16:28:14 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 16:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:14 INFO - Downloading functools32-3.2.3-2.tar.gz 16:28:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:28:14 INFO - warning: no files found matching '*.txt' 16:28:14 INFO - no previously-included directories found matching 'build' 16:28:14 INFO - no previously-included directories found matching 'dist' 16:28:14 INFO - no previously-included directories found matching '.git*' 16:28:14 INFO - Installing collected packages: jsonschema, functools32 16:28:14 INFO - Running setup.py install for functools32 16:28:14 INFO - warning: no files found matching '*.txt' 16:28:14 INFO - no previously-included directories found matching 'build' 16:28:14 INFO - no previously-included directories found matching 'dist' 16:28:14 INFO - no previously-included directories found matching '.git*' 16:28:14 INFO - Successfully installed jsonschema functools32 16:28:14 INFO - Cleaning up... 16:28:14 INFO - Return code: 0 16:28:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:28:14 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')]} 16:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:14 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')]} 16:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:14 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 16:28:14 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 16:28:14 INFO - Using env: {'DISPLAY': ':0', 16:28:14 INFO - 'HOME': '/home/cltbld', 16:28:14 INFO - 'LANG': 'en_US.UTF-8', 16:28:14 INFO - 'LANGUAGE': 'en_US:en', 16:28:14 INFO - 'LOGNAME': 'cltbld', 16:28:14 INFO - 'MAIL': '/var/mail/cltbld', 16:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:14 INFO - 'MOZ_NO_REMOTE': '1', 16:28:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:14 INFO - 'NO_EM_RESTART': '1', 16:28:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:14 INFO - 'PWD': '/builds/slave/test', 16:28:14 INFO - 'SHELL': '/bin/bash', 16:28:14 INFO - 'SHLVL': '1', 16:28:14 INFO - 'TERM': 'linux', 16:28:14 INFO - 'TMOUT': '86400', 16:28:14 INFO - 'USER': 'cltbld', 16:28:14 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:14 INFO - '_': '/tools/buildbot/bin/python'} 16:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:28:15 INFO - Cleaning up... 16:28:15 INFO - Return code: 0 16:28:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:28:15 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')]} 16:28:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:15 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')]} 16:28:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:15 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 16:28:15 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 16:28:15 INFO - Using env: {'DISPLAY': ':0', 16:28:15 INFO - 'HOME': '/home/cltbld', 16:28:15 INFO - 'LANG': 'en_US.UTF-8', 16:28:15 INFO - 'LANGUAGE': 'en_US:en', 16:28:15 INFO - 'LOGNAME': 'cltbld', 16:28:15 INFO - 'MAIL': '/var/mail/cltbld', 16:28:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:15 INFO - 'MOZ_NO_REMOTE': '1', 16:28:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:15 INFO - 'NO_EM_RESTART': '1', 16:28:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:15 INFO - 'PWD': '/builds/slave/test', 16:28:15 INFO - 'SHELL': '/bin/bash', 16:28:15 INFO - 'SHLVL': '1', 16:28:15 INFO - 'TERM': 'linux', 16:28:15 INFO - 'TMOUT': '86400', 16:28:15 INFO - 'USER': 'cltbld', 16:28:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:15 INFO - '_': '/tools/buildbot/bin/python'} 16:28:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:15 INFO - Downloading/unpacking blobuploader==1.2.4 16:28:15 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 16:28:15 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 16:28:15 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 16:28:15 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 16:28:15 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 16:28:15 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 16:28:17 INFO - Downloading blobuploader-1.2.4.tar.gz 16:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:28:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:17 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 16:28:17 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 16:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:28:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:17 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 16:28:17 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 16:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:18 INFO - Downloading docopt-0.6.1.tar.gz 16:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:28:18 INFO - Installing collected packages: blobuploader, requests, docopt 16:28:18 INFO - Running setup.py install for blobuploader 16:28:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:28:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:28:18 INFO - Running setup.py install for requests 16:28:18 INFO - Running setup.py install for docopt 16:28:18 INFO - Successfully installed blobuploader requests docopt 16:28:18 INFO - Cleaning up... 16:28:18 INFO - Return code: 0 16:28:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:18 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')]} 16:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:18 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')]} 16:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:18 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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:18 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 16:28:18 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 16:28:18 INFO - Using env: {'DISPLAY': ':0', 16:28:18 INFO - 'HOME': '/home/cltbld', 16:28:18 INFO - 'LANG': 'en_US.UTF-8', 16:28:18 INFO - 'LANGUAGE': 'en_US:en', 16:28:18 INFO - 'LOGNAME': 'cltbld', 16:28:18 INFO - 'MAIL': '/var/mail/cltbld', 16:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:18 INFO - 'MOZ_NO_REMOTE': '1', 16:28:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:18 INFO - 'NO_EM_RESTART': '1', 16:28:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:18 INFO - 'PWD': '/builds/slave/test', 16:28:18 INFO - 'SHELL': '/bin/bash', 16:28:18 INFO - 'SHLVL': '1', 16:28:18 INFO - 'TERM': 'linux', 16:28:18 INFO - 'TMOUT': '86400', 16:28:18 INFO - 'USER': 'cltbld', 16:28:18 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:18 INFO - '_': '/tools/buildbot/bin/python'} 16:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:18 INFO - Running setup.py (path:/tmp/pip-T5GjQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:18 INFO - Running setup.py (path:/tmp/pip-hISd3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:19 INFO - Running setup.py (path:/tmp/pip-JUzXd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:19 INFO - Running setup.py (path:/tmp/pip-afNWc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:19 INFO - Running setup.py (path:/tmp/pip-3R_PAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:19 INFO - Running setup.py (path:/tmp/pip-bAP65g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:19 INFO - Running setup.py (path:/tmp/pip-cSXwEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:19 INFO - Running setup.py (path:/tmp/pip-CkVC5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:19 INFO - Running setup.py (path:/tmp/pip-n2Nt9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:19 INFO - Running setup.py (path:/tmp/pip-dSaP_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:19 INFO - Running setup.py (path:/tmp/pip-2sE9yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:19 INFO - Running setup.py (path:/tmp/pip-hUqWuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:19 INFO - Running setup.py (path:/tmp/pip-uqOxgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:19 INFO - Running setup.py (path:/tmp/pip-x5HpGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:19 INFO - Running setup.py (path:/tmp/pip-DDv78t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:20 INFO - Running setup.py (path:/tmp/pip-ItyGJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:20 INFO - Running setup.py (path:/tmp/pip-HTQVDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:28:20 INFO - Running setup.py install for manifestparser 16:28:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Running setup.py install for mozcrash 16:28:20 INFO - Running setup.py install for mozdebug 16:28:20 INFO - Running setup.py install for mozdevice 16:28:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Running setup.py install for mozfile 16:28:20 INFO - Running setup.py install for mozhttpd 16:28:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Running setup.py install for mozinfo 16:28:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Running setup.py install for mozInstall 16:28:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:28:20 INFO - Running setup.py install for mozleak 16:28:21 INFO - Running setup.py install for mozlog 16:28:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Running setup.py install for moznetwork 16:28:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Running setup.py install for mozprocess 16:28:21 INFO - Running setup.py install for mozprofile 16:28:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Running setup.py install for mozrunner 16:28:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Running setup.py install for mozscreenshot 16:28:21 INFO - Running setup.py install for moztest 16:28:21 INFO - Running setup.py install for mozversion 16:28:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:28:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:28:21 INFO - Cleaning up... 16:28:21 INFO - Return code: 0 16:28:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28:21 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')]} 16:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:21 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')]} 16:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:21 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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:21 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 16:28:21 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 16:28:21 INFO - Using env: {'DISPLAY': ':0', 16:28:21 INFO - 'HOME': '/home/cltbld', 16:28:21 INFO - 'LANG': 'en_US.UTF-8', 16:28:21 INFO - 'LANGUAGE': 'en_US:en', 16:28:21 INFO - 'LOGNAME': 'cltbld', 16:28:21 INFO - 'MAIL': '/var/mail/cltbld', 16:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:21 INFO - 'MOZ_NO_REMOTE': '1', 16:28:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:21 INFO - 'NO_EM_RESTART': '1', 16:28:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:21 INFO - 'PWD': '/builds/slave/test', 16:28:21 INFO - 'SHELL': '/bin/bash', 16:28:21 INFO - 'SHLVL': '1', 16:28:21 INFO - 'TERM': 'linux', 16:28:21 INFO - 'TMOUT': '86400', 16:28:21 INFO - 'USER': 'cltbld', 16:28:21 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:21 INFO - '_': '/tools/buildbot/bin/python'} 16:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:28:22 INFO - Running setup.py (path:/tmp/pip-6EQ7bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:28:22 INFO - Running setup.py (path:/tmp/pip-iQcifk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:28:22 INFO - Running setup.py (path:/tmp/pip-JgvuhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:28:22 INFO - Running setup.py (path:/tmp/pip-20embV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:28:22 INFO - Running setup.py (path:/tmp/pip-J46GBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:28:22 INFO - Running setup.py (path:/tmp/pip-uDJVmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:28:22 INFO - Running setup.py (path:/tmp/pip-qbVWAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:28:22 INFO - Running setup.py (path:/tmp/pip-eDBtSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:28:22 INFO - Running setup.py (path:/tmp/pip-86R3dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:28:22 INFO - Running setup.py (path:/tmp/pip-klPPJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:28:22 INFO - Running setup.py (path:/tmp/pip-3WSFfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:28:22 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)) 16:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:28:22 INFO - Running setup.py (path:/tmp/pip-XA7ssK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:28:23 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)) 16:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:28:23 INFO - Running setup.py (path:/tmp/pip-gsd93X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:28:23 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)) 16:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:28:23 INFO - Running setup.py (path:/tmp/pip-Gpycgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:28:23 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)) 16:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:23 INFO - Running setup.py (path:/tmp/pip-O51j5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:28:23 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)) 16:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:28:23 INFO - Running setup.py (path:/tmp/pip-6nNqiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:28:23 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)) 16:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:28:23 INFO - Running setup.py (path:/tmp/pip-usA7Vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:28:23 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)) 16:28:23 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)) 16:28:23 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)) 16:28:23 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)) 16:28:23 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)) 16:28:23 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)) 16:28:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:23 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 16:28:23 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 16:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:28:25 INFO - Downloading blessings-1.6.tar.gz 16:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:28:25 INFO - Installing collected packages: blessings 16:28:25 INFO - Running setup.py install for blessings 16:28:25 INFO - Successfully installed blessings 16:28:25 INFO - Cleaning up... 16:28:25 INFO - Return code: 0 16:28:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:28:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:25 INFO - Reading from file tmpfile_stdout 16:28:25 INFO - Current package versions: 16:28:25 INFO - argparse == 1.2.1 16:28:25 INFO - blessings == 1.6 16:28:25 INFO - blobuploader == 1.2.4 16:28:25 INFO - docopt == 0.6.1 16:28:25 INFO - functools32 == 3.2.3-2 16:28:25 INFO - jsonschema == 2.5.1 16:28:25 INFO - manifestparser == 1.1 16:28:25 INFO - mozInstall == 1.12 16:28:25 INFO - mozcrash == 0.17 16:28:25 INFO - mozdebug == 0.1 16:28:25 INFO - mozdevice == 0.48 16:28:25 INFO - mozfile == 1.2 16:28:25 INFO - mozhttpd == 0.7 16:28:25 INFO - mozinfo == 0.9 16:28:25 INFO - mozleak == 0.1 16:28:25 INFO - mozlog == 3.3 16:28:25 INFO - moznetwork == 0.27 16:28:25 INFO - mozprocess == 0.23 16:28:25 INFO - mozprofile == 0.28 16:28:25 INFO - mozrunner == 6.12 16:28:25 INFO - mozscreenshot == 0.1 16:28:25 INFO - mozsystemmonitor == 0.3 16:28:25 INFO - moztest == 0.7 16:28:25 INFO - mozversion == 1.4 16:28:25 INFO - psutil == 3.1.1 16:28:25 INFO - requests == 1.2.3 16:28:25 INFO - wsgiref == 0.1.2 16:28:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:25 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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:25 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 16:28:25 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 16:28:25 INFO - Using env: {'DISPLAY': ':0', 16:28:25 INFO - 'HOME': '/home/cltbld', 16:28:25 INFO - 'LANG': 'en_US.UTF-8', 16:28:25 INFO - 'LANGUAGE': 'en_US:en', 16:28:25 INFO - 'LOGNAME': 'cltbld', 16:28:25 INFO - 'MAIL': '/var/mail/cltbld', 16:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:25 INFO - 'MOZ_NO_REMOTE': '1', 16:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:25 INFO - 'NO_EM_RESTART': '1', 16:28:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:25 INFO - 'PWD': '/builds/slave/test', 16:28:25 INFO - 'SHELL': '/bin/bash', 16:28:25 INFO - 'SHLVL': '1', 16:28:25 INFO - 'TERM': 'linux', 16:28:25 INFO - 'TMOUT': '86400', 16:28:25 INFO - 'USER': 'cltbld', 16:28:25 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:25 INFO - '_': '/tools/buildbot/bin/python'} 16:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 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)) 16:28:25 INFO - Cleaning up... 16:28:25 INFO - Return code: 0 16:28:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:28: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')]} 16:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28: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')]} 16:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:28:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xe9a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xffd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x115b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', '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 16:28:25 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 16:28:25 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 16:28:25 INFO - Using env: {'DISPLAY': ':0', 16:28:25 INFO - 'HOME': '/home/cltbld', 16:28:25 INFO - 'LANG': 'en_US.UTF-8', 16:28:25 INFO - 'LANGUAGE': 'en_US:en', 16:28:25 INFO - 'LOGNAME': 'cltbld', 16:28:25 INFO - 'MAIL': '/var/mail/cltbld', 16:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:25 INFO - 'MOZ_NO_REMOTE': '1', 16:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:25 INFO - 'NO_EM_RESTART': '1', 16:28:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:25 INFO - 'PWD': '/builds/slave/test', 16:28:25 INFO - 'SHELL': '/bin/bash', 16:28:25 INFO - 'SHLVL': '1', 16:28:25 INFO - 'TERM': 'linux', 16:28:25 INFO - 'TMOUT': '86400', 16:28:25 INFO - 'USER': 'cltbld', 16:28:25 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:25 INFO - '_': '/tools/buildbot/bin/python'} 16:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:28:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:28:26 INFO - Cleaning up... 16:28:26 INFO - Return code: 0 16:28:26 INFO - Running post-action listener: _resource_record_post_action 16:28:26 INFO - Running post-action listener: _start_resource_monitoring 16:28:26 INFO - Starting resource monitoring. 16:28:26 INFO - [mozharness: 2016-08-07 23:28:26.224496Z] Finished create-virtualenv step (success) 16:28:26 INFO - [mozharness: 2016-08-07 23:28:26.224926Z] Running install step. 16:28:26 INFO - Running pre-action listener: _resource_record_pre_action 16:28:26 INFO - Running main action method: install 16:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:28:26 INFO - Reading from file tmpfile_stdout 16:28:26 INFO - Detecting whether we're running mozinstall >=1.0... 16:28:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:28:26 INFO - Reading from file tmpfile_stdout 16:28:26 INFO - Output received: 16:28:26 INFO - Usage: mozinstall [options] installer 16:28:26 INFO - Options: 16:28:26 INFO - -h, --help show this help message and exit 16:28:26 INFO - -d DEST, --destination=DEST 16:28:26 INFO - Directory to install application into. [default: 16:28:26 INFO - "/builds/slave/test"] 16:28:26 INFO - --app=APP Application being installed. [default: firefox] 16:28:26 INFO - mkdir: /builds/slave/test/build/application 16:28:26 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'] 16:28:26 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 16:28:42 INFO - Reading from file tmpfile_stdout 16:28:42 INFO - Output received: 16:28:42 INFO - /builds/slave/test/build/application/firefox/firefox 16:28:42 INFO - Running post-action listener: _resource_record_post_action 16:28:42 INFO - [mozharness: 2016-08-07 23:28:42.700127Z] Finished install step (success) 16:28:42 INFO - [mozharness: 2016-08-07 23:28:42.700377Z] Running run-tests step. 16:28:42 INFO - Running pre-action listener: _resource_record_pre_action 16:28:42 INFO - Running main action method: run_tests 16:28:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:28:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:28:42 INFO - Python 2.7.3 16:28:42 INFO - Return code: 0 16:28:42 INFO - grabbing minidump binary from tooltool 16:28:42 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')]} 16:28:42 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 0x11593b0>, '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 0x115a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x115a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:28:42 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 16:28:42 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 16:28:42 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 16:28:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:28:42 INFO - Return code: 0 16:28:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:28:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:28:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:28:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:28:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:28:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:28:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 16:28:42 INFO - Using env: {'DISPLAY': ':0', 16:28:42 INFO - 'HOME': '/home/cltbld', 16:28:42 INFO - 'LANG': 'en_US.UTF-8', 16:28:42 INFO - 'LANGUAGE': 'en_US:en', 16:28:42 INFO - 'LOGNAME': 'cltbld', 16:28:42 INFO - 'MAIL': '/var/mail/cltbld', 16:28:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:28:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:28:42 INFO - 'MOZ_NO_REMOTE': '1', 16:28:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:28:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:28:42 INFO - 'NO_EM_RESTART': '1', 16:28:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:28:42 INFO - 'PWD': '/builds/slave/test', 16:28:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:28:42 INFO - 'SHELL': '/bin/bash', 16:28:42 INFO - 'SHLVL': '1', 16:28:42 INFO - 'TERM': 'linux', 16:28:42 INFO - 'TMOUT': '86400', 16:28:42 INFO - 'USER': 'cltbld', 16:28:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935', 16:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:28:42 INFO - '_': '/tools/buildbot/bin/python'} 16:28:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 16:28:42 INFO - mozversion application_buildid: 20160807150052 16:28:42 INFO - mozversion application_changeset: 2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0 16:28:42 INFO - mozversion application_display_name: Nightly 16:28:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:28:42 INFO - mozversion application_name: Firefox 16:28:42 INFO - mozversion application_remotingname: firefox 16:28:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:28:42 INFO - mozversion application_vendor: Mozilla 16:28:42 INFO - mozversion application_version: 51.0a1 16:28:42 INFO - mozversion platform_buildid: 20160807150052 16:28:42 INFO - mozversion platform_changeset: 2c1f6bf57d21f2bb3dda621bfc21db36e6539fb0 16:28:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:28:42 INFO - mozversion platform_version: 51.0a1 16:28:42 INFO - using testdate: 1470612522 16:28:42 INFO - actual date: 1470612522 16:28:42 INFO - starting webserver on 'localhost:47960' 16:28:42 INFO - SUITE-START | Running 2 tests 16:28:42 INFO - TEST-START | dromaeo_css 16:28:42 INFO - operating with platform_type : linux_ 16:28:42 INFO - Initialising browser for dromaeo_css test... 16:28:42 INFO - TEST-INFO | started process 17381 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3aDDLM/profile http://localhost:47960/getInfo.html) 16:28:47 INFO - PROCESS | 17381 | __metrics Screen width/height:1600/1200 16:28:47 INFO - PROCESS | 17381 | colorDepth:24 16:28:47 INFO - PROCESS | 17381 | Browser inner width/height: 1024/697 16:28:47 INFO - PROCESS | 17381 | __metrics 16:28:48 INFO - PROCESS | 17381 | 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" 16:28:48 INFO - PROCESS | 17381 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:49 INFO - TEST-INFO | 17381: exit 0 16:28:49 INFO - Browser initialized. 16:28:49 INFO - Running cycle 1/1 for dromaeo_css test... 16:28:49 INFO - TEST-INFO | started process 17461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3aDDLM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:01 INFO - PROCESS | 17461 | RSS: Main: 157700096 16:29:01 INFO - PROCESS | 17461 | 16:29:52 INFO - PROCESS | 17461 | 1470612591995 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] 16:32:05 INFO - PROCESS | 17461 | Cycle 1(1): loaded http://localhost:47960/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47960/tests/dromaeo/cssquery-ext.html) 16:32:06 INFO - PROCESS | 17461 | RSS: Main: 240738304 16:32:06 INFO - PROCESS | 17461 | 16:35:09 INFO - PROCESS | 17461 | Cycle 1(1): loaded http://localhost:47960/tests/dromaeo/cssquery-ext.html (next: http://localhost:47960/tests/dromaeo/cssquery-jquery.html) 16:35:09 INFO - PROCESS | 17461 | RSS: Main: 250216448 16:35:09 INFO - PROCESS | 17461 | 16:38:12 INFO - PROCESS | 17461 | Cycle 1(1): loaded http://localhost:47960/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47960/tests/dromaeo/cssquery-mootools.html) 16:38:12 INFO - PROCESS | 17461 | RSS: Main: 230510592 16:38:12 INFO - PROCESS | 17461 | 16:41:14 INFO - PROCESS | 17461 | Cycle 1(1): loaded http://localhost:47960/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47960/tests/dromaeo/cssquery-prototype.html) 16:41:15 INFO - PROCESS | 17461 | RSS: Main: 249012224 16:41:15 INFO - PROCESS | 17461 | 16:44:18 INFO - PROCESS | 17461 | Cycle 1(1): loaded http://localhost:47960/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47960/tests/dromaeo/cssquery-yui.html) 16:44:19 INFO - PROCESS | 17461 | RSS: Main: 192978944 16:44:19 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | Cycle 1(1): loaded http://localhost:47960/tests/dromaeo/cssquery-yui.html (next: http://localhost:47960/tests/dromaeo/cssquery-dojo.html) 16:47:22 INFO - PROCESS | 17461 | RSS: Main: 281604096 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | __start_tp_report 16:47:22 INFO - PROCESS | 17461 | _x_x_mozilla_page_load 16:47:22 INFO - PROCESS | 17461 | _x_x_mozilla_page_load_details 16:47:22 INFO - PROCESS | 17461 | |i|pagename|runs| 16:47:22 INFO - PROCESS | 17461 | |0;dojo.html;2658.388570628755;2673.5588627876377;2701.5542435498137;2708.377073273146;2712.7422894825;4600.033992861495;4666.160091183586;4787.090452813967;4926.06404783091;4941.752912354382;4774.522547745224;4806.038792241548;4820.108279968208;4833.492483289257;4851.805927762885;4285.871423857274;4330.155619654177;4414.690971631987;4464.776761161945;4596.034832685124;4515.051839113774;4546.181687296285;4570.9314360284625;4589.518100599439;4591.5867571918525;4222.4299719538085;4257.914841703179;4459.710118842265;4472.84345047925;4563.703359281652;4320.027993701424;4329.328954012133;4392.3411488277015;4418.425604671372;4449.021215332622;4271.914561708749;4275.123599662062;4328.480582330109;4331.588499092612;4481.5070342262325;4727.527247275245;4755.286706993944;4786.928196077061;4792.065547218293;4799.880002999918;4974.228994505857;5050.84847454577;5074.695518268915;5107.055194788976;5122.795088196468;5247.055530004598;5257.1588545832865;5257.553107985787;5283;5314.707691076989;4833.130036593447;4837.854864354075;4856.028794241166;4860.80556777732;4947.084789313988;5038.622103342264;5193.76628051731;5226.764795584208;5227.869303267449;5403.9459605403445;5058.64589478733;5092.10888094582;5107.846764597068;5109;5115.948840511547;5135.974320128375;5136.383633963948;5139.254808052811;5185.962807438527;5187.195984622314;4763.666543342002;4884.5603895649565;5003.624728145331;5017.12200364942;5018.322526458955;5036.445990940993;5036.471170527115;5039.571636410873;5041.974790126099;5081.2378143278065;4328.458942632171;4331.588499092644;4335.479742430928;4365.345198220292;4372.519022907478;3726.571444283942;3728.0307120148955;3754.099016236138;3762.0406796266775;3782.149016471272;4354.586314300136;4355.303151495746;4375.146846364928;4385.890352741207;4398.846040388571;5121.513456221653;5123.257127716461;5124.66689665178;5125.846224613268;5171.741412929338;4211.052513184509;4336.848210312624;4377.343398490252;4407.867763967086;4464.598186163261;4693.319468677023;4707.058588282357;4714.976425117852;4745.6440766942615;4754.191787396151;4932.630052745986;4937.40751109862;4941.061198372297;4941.851744447672;4962.826301079445;4915.11527924977;4920;5049.697018178921;5273.209018647233;5330.333708286465;4822.903541929071;4847.587955023862;4865.440474345495;4887.511248875084;4901.681390709592;5054.342935418372;5058.797648094035;5066.392032956069;5083.415407228216;5107.284980102863;4951.752412379438;5126.077306084941;5157.458466860926;5172.896542069062;5175.508326708957;5002.87492812668;5063.822766203165;5069.340985671839;5111.974440127776;5122.615803814654;5041.218611115283;5041.294218809297;5044.192929131468;5058.367704036996;5102.974485127699;4755.310479980314;4928.2853986171085;4979.9004019918675;4989.750512474435;5042.873928151826;5049.924251136309;5079.746012699424;5091.312672789126;5138.07514647367;5141.614378921521;4963.875903102451;4986;4993.80024799004;5015.222640490584;5057.848264552069 16:47:22 INFO - PROCESS | 17461 | |1;ext.html;582.1878479521068;595.7289433307845;602.3795490644637;613.0742578706153;627.1188979483827;28260.43479130416;31739.682603173962;39011.80494097529;40375;40531.594684053154;40178.7991060045;40398.59601403985;40424.39363409544;40616.390754138636;40641.390379144264;7482.438817088727;7640.274173953493;7641.930129781835;7644.732434364799;7672.46292759507;7486.887696684554;7589.241075892422;7592.848143037136;7597.81005474862;7598.9620051899665;7593.05086864142;7630.580318082503;7631.503952243114;7633.961830190841;7643.197464266255;7582.734604288851;7603.163651998276;7616.238376162395;7618.085829700416;7620.733274335427;7664.31021208094;7726.7681969541;7728.033995750531;7731.961340193263;7741.109772376192;38358.424623630664;38577.421338679946;38594.80702596497;38602;38712;3133.3576616793503;3199.8240096794666;3257.5765150530515;3336.6663333666684;3559.4126969050335;29068;29368.853155734083;29552.556711649344;29746.85126574353;29865.55201671977;3550.431930891045;3649.8540058397634;3718.51659284294;3719.460678201646;3771.830267637963;253641;255971;257144;258499;258917;30962.69037309643;31885.362292754016;47481.28778068332;47662;47709;34298;34521.48217776736;34602.82698586542;34633.826830865684;34637.82681086579;12375;12475.812862807064;12490.687732806753;12509.437075331633;12523.56167534132;10030.448325342177;10143.08712215904;10143.543540540693;10148.797024059477;10153.340032897839;7930.246626570467;7953.045634523778;7994.160613135653;8030.397720170894;8062.959685201537;3130.2800355918266;3131.7024882636115;3134.843257837099;3138.466460701686;3173.1273899677312;814.4136221920209;866.1165611076666;875.3434923807145;925.8842644669417;990.9752256193652;27179.184624461297;27490.725092748817;27840.303992399946;28313.292167695974;28751.56872646912;968.9806203875883;977.9168770654431;1012.4482157224184;1015.8882522922472;1037.8754549454113;14593.708125837424;14737.263136843116;14846.92576537132;14876.107433553805;15026.098434093989;382018;385379;385780;386964;387242;49775.502244977084;49792.50207497951;50044.249336259985;50432.243516347284;50595;1091.8853520380405;1118.7594667146602;1189.232944750631;1190.33936165429;1218.0620921890272;26554.468910622065;28802.855985719936;29690.758684002936;29868.70131298746;29937;41693.58306417018;42277.57722422737;42277.78861105675;42278.154436911704;42310.78844605757;319066;319496;320360;321219;321597;22997.195098171484;25540.744592553838;39491.605083948794;40461;40976.385354219114;39627.80186099166;43146.35280470733;43488;43848.56151438445;44933;36284.45573316455;36981.260374792895;37057;37454;37584.624153758115;36006.099847504025;36236.63763362333;36495.08762280964;36795.63204367922;37531.43702844514;36866.8156659215;37375.2524949494;37853.242935141694;37904.620953791215;37934.62065379311;31934.84032579822;33522.32955340928;33822.49266261057;33886.66113338835;33931;25527;25625.23124306274;25814.354641134123;25832;26240.73759262383 16:47:22 INFO - PROCESS | 17461 | |2;jquery.html;350.85790254946755;363.3985753577828;364.7446787248926;368.0614433195351;377.7392307042351;16161;17201.913990430046;17626.559336016606;17659.55851103723;17729.64540709185;16660.75008874868;17432.477025689248;17693.64612707745;17834.55413614657;17854.375096871612;4895.730734809576;5121.9487805122035;5124.538791508763;5124.564412024983;5130;3767.284215998965;3947.54603220629;5115.488451154892;5742.454466825666;5973.790917317895;6085.543584231178;6086.939130608704;6090.208272924536;6092.08618707193;6101.694915254242;5842.386549412315;6009.939900600982;6023.156758053876;6026.06595977622;6031.668258245794;4672.299155126724;5218.817341393033;5229.2156176573435;5240.764165612557;5256.737163141827;19366.903165484222;20990;21135.365939021853;21138.365849024554;21304.36086917395;1783.9197236124405;1858.8419984301352;1876.8967706776123;1884.9717254241198;1946.4939115829986;8871.778205544913;8878.467291962503;8987.41581797181;9343.205827504671;9555.904440955572;1702.1829521829468;1740.7301868210448;1789.3021721528614;1799.4601619514094;1812.619349936515;66458;69604.6519767403;70374.29625703729;71281.64359178222;74223.62888185578;9260.907390926004;9285.535723213812;9350.784398028212;9526.285528585384;9529.332946693665;12471.688207794696;12544.184627999151;12631.178973366701;12732.554360597334;12771.552995645108;6473.967630161819;6518.608883467007;6908.447324214051;7041.366277035091;7060.71757129709;6539.50953678476;6608.537402381883;6776.661166941732;6781.93218067813;6805.557638753465;4062.370332598452;4156.501219853576;4207.579242075829;4231.767252801125;4342.240107981091;1582.311694412911;1583.6515966487354;1677.6057626457773;1737.8435940765391;1768.2396569474993;377.49981274810983;885.6413152673177;945.7635591102224;960.2126256469628;969.8351280282369;10094.343867648578;10807.513661885234;10940.45297735126;10952.945235273774;11156.219064665396;396.64896566538823;402.8287977609504;403.8869116647343;412.83899279281104;414.3411974959768;5553.944460555424;5744.253247077853;5823.242978412779;6055.697215139226;6082.847928801727;353435;356694;374990.5009499028;380895;390043;78713.60643196861;79428;80518;80532.19467805364;80795;421.3279818689186;429.810883211386;430.26424813568525;430.3179460554993;431.0516862901629;9961.153301969414;10065.547050382896;10204.38773673582;10257.897421025695;10313.587456501657;39103.804480977415;39748.80125599354;39985;40299.3955090668;40331.395029075175;51408.228876566136;53908.73045634747;54405.18392224041;54763.72618136884;56028.43971560232;7286.890696639449;7589.1272503662785;7633.770986870402;7643.694252230071;7656.73201437947;8705.434146780439;11286.548538058385;11736.823947640622;11827.231229970024;12039.819402708792;9893.604255829687;10135.7466063349;10144.985501449795;10288.691339259834;10790.784184316426;10881.673549793519;10957.452127393757;10961.342319460859;11169.664910052712;11203.775924481628;10725.141988640735;11109.611163609236;11251.606193783178;11254.155938304824;11324.32054076758;7674.9232507674205;7956.681732730626;8092.069412017458;8174.550399728072;8214.096449390443;10127.189824814144;10589.152867770406;10923.836142458029;10990.560377584807;11296.49165787525 16:47:22 INFO - PROCESS | 17461 | |3;mootools.html;1371.9862801371983;1418.8864890808734;1489.5605796290092;1649.381481944271;1726.6719323328566;6481.157449531566;6659.766908158215;7246.130464344279;7799.649015794289;7800.570968596725;7079.150501939775;7331.816704582388;7347.559146451216;7536.698532058725;7541.057367829021;2188.8796116213603;2201.8348623853194;2213.0815711479718;2215.700880381144;2217.523232505011;1765.0556952030665;2087.3737878636425;2114.164904862578;2148.1729534129317;2155.4503601581573;2216.9603964352755;2251.966220506694;2252.549490101978;2253.1663284584724;2256.4133325335447;2031.2687432524278;2057.32108404227;2188.8139508141826;2192.6053310404122;2193.451637090727;2145.731783527059;2157.169489746443;2166.393409845246;2166.6425039868404;2169.1648715244746;7922.485038472539;7998.64006119726;8003.559804210766;8014.238647328494;8014.759557213293;4381.605655491021;4492.056668099703;4538.7503687297185;4539.931901021488;4548.0221752323405;2774.4589804988036;2877.438899414615;2886.7546258568045;2923.283795470102;2936.3393236521824;876.4040452492303;925.2459245714788;926.9814603707955;932.8460803967337;934.3552948465536;9935.155511781508;9935.403875767477;9935.65225217114;9938.204943604494;9940.701778946643;3720.8325625346924;4005.499312585927;4154.065335299594;4154.439150714676;4158;8602.87095693565;8631.6978905738;8657;8674.479531228146;8681.348898832613;4455.844045458393;4493.618042466427;4496.955030449719;4499.595036446735;4500.167469018242;4042.1348731942167;4071.898202544901;4077.245709543744;4080.4491393661474;4082.9996650820144;3563.821808909596;3665.2669466106886;3689.0408493791806;3694.6305369463375;3698.6671199592165;991.494337887668;1080.0873262093519;1100.7963526747578;1134.8241022641503;1138.1520767028617;805.0822062848357;832.9791755206169;837.7026155714756;839.366278459759;850.1498501498502;5077.086124497552;5561.63849349791;5663.631863928831;5761.625494342854;5841.386654401312;874.2437791310471;893.8033632600817;897.2059727141527;899.7615631857562;906.7370462565914;3709.4250391189407;3789.734718569729;3817.312883680964;3819.1216020315483;3851.730378873508;9543.522823858779;9789.608415663293;9874.703758887244;9880.505974701236;9881;9686.564104615169;9768.120869121813;9777;9781;9797.706068817804;6901.171859376806;7019.543729657556;7026.718931242795;7028.121484814398;7029.92970070303;4965.106280869478;5012.699238045583;5028.547430731252;5119.206522988943;5200.869978250423;8618.181272779077;8887.777805554913;8918.910810891808;9099.408538444979;9123.452592844451;8959.148880856308;9013;9113.407628504126;9143.679971200976;9213.539323033956;6396.200474940632;6487.286398496068;6500.122483464672;6548.574342667712;6558.475321974326;7536.773896783106;7537.773866784005;7561.886571701539;7569;7574.053243344582;6448.742050318122;6637.734490620321;6687.063811066358;6781.355771201728;6842.521023528305;6416.294207637063;6558.672066396756;6663.666816659244;6932.792016239521;6982.476314276348;6218.5647004711045;6246.812595622138;6264;6291.528135389773;6301.464375528131;4513.729176249435;4526.321051842118;4645.163870503342;4733.83431579893;4790.568848803624;4701.811927522997;4740.265258884879;4954.256861470808;5014.598832093497;5023.598112150946 16:47:22 INFO - PROCESS | 17461 | |4;prototype.html;283.968763436022;299.3280086206467;303.4219811259551;304.4018503640346;308.33861367367;4665.673402861801;4782.330473733676;4821.975890120553;4853.563179313861;4863.465018847928;4614.538546145383;4762.309465127554;4764.190087685093;4771.284307353899;4788.40869347989;3802.638749318824;3809.657130858222;3818.3508803503332;3825.7810469927513;3836.213576216876;3525.7708248963863;3833.5016447861876;3834.328992426328;3838.309104361214;3847.557530883942;3598.352296586613;3733.290674771785;3755.324041672498;3774.433834924756;3784.5080139581955;3712.406015037581;3751.6985053153053;3753.7935413552245;3754.8923067695096;3756.192418629994;3325.567676202078;3329.916752081193;3330.134165117087;3330.2516179005975;3333.599968003831;4519.615832654228;4669.34629151919;4673.836415725467;4701.48283688794;4735.076660051292;4256.574342565718;4269.124829409963;4274.871753847358;4287.871363859089;4293.506246781598;4967.602591792648;4992.101421744084;4994.275830004629;4995.9500404996215;5013.448520662724;3559.2347645256395;3661.5972243053243;3679.3193259247123;3684.6683798458266;3699.7780133191827;5184.559312458446;5263.0526505229045;5270.7628156733035;5276.419593844674;5280.49835265653;3776.962230377661;3786.4320351947426;3789.7915614640906;3796.9430458543143;3817.351050321452;3726.6273372662517;3866.6326698963553;3876.4185372194397;3880.670143037817;3881.5536213335827;4351.717138385995;4352.173087113439;4352.586504282088;4354;4384.188925048876;5066.290719299301;5067.5185857343495;5077.873053173626;5092.541671249605;5099.260607212008;1185.5376403202758;1257.9685507862193;1333.8866196373224;1344.8318960129984;1348.4673553946136;920.1488623023677;931.937591146094;934.1265916368218;934.6308208257836;936.194872409727;4418.646508279394;4420.447444069491;4421.314696222091;4430.933535996963;4441.111777644484;5152.175651895754;5160;5167.896642067213;5173.198154286091;5193.922091168635;4269.825797905539;4404.4714634243455;4414.514403415622;4419.801108950105;4435.090806384684;2419.552382809186;2425.5755242832433;2436.7075950886006;2439.7438268981855;2444.4255599934;4287.3140297552245;4324.545922678137;4334.13317336534;4350.390945267665;4366.104948485553;4463.843765468193;4608.5161058088415;4626.976865115653;4645.883852903705;4667.346571479996;4267.015837635515;4320.114376552862;4320.4383430153875;4323.827046918151;4328.350747387917;4556.863294101182;4561.977190114028;4564;4570.931436028529;4579.702319349231;4295.906946998156;4445.510993790615;4482.820687172477;4491.505934347285;4509.571590698878;4253.9361909572;4264.42216379929;4302.978485107555;4316.589923957219;4329.718568293051;3413.753979797406;3446.9482957756154;3447.9482807757404;3452.016175390002;3584.964150358463;5004.099262132851;5166.793328266827;5184.948150518598;5191.16941289407;5198.2202669599865;5003.599712023102;5012.1980483122525;5031.270465782514;5033.320501732219;5086.516780905808;4876.414830220397;5035.924461133012;5053.140966035856;5054.519820617035;5058.7976480941825;4877.975610121926;4912.680675756064;4927.679700819435;4936.605071594336;4952.900941981212;5034.802900158353;5051.772670229922;5082.288479612783;5088.1604535252745;5103.770330335218;4806.975965120152;4934.925976110289;4935.087008903364;4966.8758281043265;4981.227909674007 16:47:22 INFO - PROCESS | 17461 | |5;yui.html;635.4789072960173;636.4272155060443;640.5484133685749;641.1216633212498;693.1855070292405;12990.155639883413;13322.333883305844;14053.227072511008;14128;14151.504697335582;13433.328333583331;13647.590572282823;13660.931695341535;13699;13702.246376449291;643.671727419015;772.3473664753275;782.202153803119;791.0309870408732;803.3492870774662;786.6735304848481;792.2434075457924;792.2513225002375;792.2671528835832;792.3581898662073;827.9917200828005;828.2711214131556;828.8632375658009;829.6598394658191;829.6930135849744;660.4056349285634;807.8384323135397;821.256762629821;821.5892053973014;824.2540500846743;607.0802733858209;607.2712744706325;607.3046361915582;607.781198768443;607.8389226854887;12698.365081745877;12705.936470317682;12720.809187862189;12751.426185821567;12760.489580416772;12221;12559.811602825965;12921.870781292255;13154.342282885817;13582.388792504362;9352.158305752515;9576.329656924017;10358.041609567708;10416.166706663449;10576.735581610445;4081.5102187737366;4082.203970225807;4104.692148088905;4169.332906734938;4302.698811083225;61611.691941540455;61961.38038619601;62313.376866231665;63543.36456635467;63589;9503.132436742675;9672.70981870545;9701.240359386147;9794.40887581019;9835.409875407498;17925.551861203312;17934.5516362092;18112.909435452737;18114.728279075825;18119.72820407695;6383.138276332728;6507.577007494498;6518.543701940818;6598.43913267368;6633.900491492632;3758.1168425420005;3787.1289603391374;3805.5813860475328;3884.8251828667776;3885.611438856148;2737.7262273772462;2823.364742932865;2826.3640680846647;2841.77265818734;2869.684334723179;488.36024807502287;492.6896055485093;495.1261024292127;511.6801998750781;511.9283300337956;485.24786580800327;489.18306428264884;509.162427806264;511.2433598274574;549.3380476525776;8253.33973282148;8318.625661845232;8418.873716894252;8485.278751306209;8722.738317850475;515.8039944820944;518.1657531374484;526.6339893773791;542.2219115569171;633.7179954920108;4047.372657238133;4063.6342729154517;4152.397902304209;4157.64660003897;4321.0709697415205;58138;77562.61218693983;81802.77465316835;106050.46974765077;106421;59508;87742.56128719315;91974.54012729894;92063.07936920546;92467.5376623126;47716.284255736195;56962.145567816515;73602;74504;77461.61269193619;7100.431965442649;7129.536580122275;7135.215126336203;7311.195768465358;7443.4789564729945;46114.538854611026;46719;47810.28284575804;48178.75910620424;48872;64961;69709.30290697028;70306;70442.64778676073;73293;10482.737931551772;10504.527296271839;10535.420551869414;10621;10647.94676026615;10703.946480267548;10749.62376316825;10905.454727263765;11931.105167112675;12272.711365306513;10122;10912.454377281263;10995.285306455055;11033.834492482458;11574.71063223426;10875.673729788119;11036.448177590928;11298.3496904425;11316.830247546459;11485.368304743319;10991.835122473329;11216.83174752363;11485.655430337103;11850.466728997388;11955.820662689892;9050.502222377832;9068.863967040368;9345.158935695548;9389.254752207074;9443.291753118407;8297.25324720778;8785.297176226015;9242.260619150586;9424.198943089914;9438.52807359643 16:47:22 INFO - PROCESS | 17461 | __end_tp_report 16:47:22 INFO - PROCESS | 17461 | __start_cc_report 16:47:22 INFO - PROCESS | 17461 | _x_x_mozilla_cycle_collect,997 16:47:22 INFO - PROCESS | 17461 | __end_cc_report 16:47:22 INFO - PROCESS | 17461 | __startTimestamp1470613642381__endTimestamp 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | ------- Summary: start ------- 16:47:22 INFO - PROCESS | 17461 | Number of tests: 6 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | [#0] dojo.html Cycles:165 Average:4750.91 Median:4926.06 stddev:506.79 (10.3%) stddev-sans-first:481.02 16:47:22 INFO - PROCESS | 17461 | Values: 2658.4 2673.6 2701.6 2708.4 2712.7 4600.0 4666.2 4787.1 4926.1 4941.8 4774.5 4806.0 4820.1 4833.5 4851.8 4285.9 4330.2 4414.7 4464.8 4596.0 4515.1 4546.2 4570.9 4589.5 4591.6 4222.4 4257.9 4459.7 4472.8 4563.7 4320.0 4329.3 4392.3 4418.4 4449.0 4271.9 4275.1 4328.5 4331.6 4481.5 4727.5 4755.3 4786.9 4792.1 4799.9 4974.2 5050.8 5074.7 5107.1 5122.8 5247.1 5257.2 5257.6 5283.0 5314.7 4833.1 4837.9 4856.0 4860.8 4947.1 5038.6 5193.8 5226.8 5227.9 5403.9 5058.6 5092.1 5107.8 5109.0 5115.9 5136.0 5136.4 5139.3 5186.0 5187.2 4763.7 4884.6 5003.6 5017.1 5018.3 5036.4 5036.5 5039.6 5042.0 5081.2 4328.5 4331.6 4335.5 4365.3 4372.5 3726.6 3728.0 3754.1 3762.0 3782.1 4354.6 4355.3 4375.1 4385.9 4398.8 5121.5 5123.3 5124.7 5125.8 5171.7 4211.1 4336.8 4377.3 4407.9 4464.6 4693.3 4707.1 4715.0 4745.6 4754.2 4932.6 4937.4 4941.1 4941.9 4962.8 4915.1 4920.0 5049.7 5273.2 5330.3 4822.9 4847.6 4865.4 4887.5 4901.7 5054.3 5058.8 5066.4 5083.4 5107.3 4951.8 5126.1 5157.5 5172.9 5175.5 5002.9 5063.8 5069.3 5112.0 5122.6 5041.2 5041.3 5044.2 5058.4 5103.0 4755.3 4928.3 4979.9 4989.8 5042.9 5049.9 5079.7 5091.3 5138.1 5141.6 4963.9 4986.0 4993.8 5015.2 5057.8 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | [#1] ext.html Cycles:180 Average:46577.24 Median:20547.36 stddev:85728.76 (417.2%) stddev-sans-first:85899.70 16:47:22 INFO - PROCESS | 17461 | Values: 582.2 595.7 602.4 613.1 627.1 28260.4 31739.7 39011.8 40375.0 40531.6 40178.8 40398.6 40424.4 40616.4 40641.4 7482.4 7640.3 7641.9 7644.7 7672.5 7486.9 7589.2 7592.8 7597.8 7599.0 7593.1 7630.6 7631.5 7634.0 7643.2 7582.7 7603.2 7616.2 7618.1 7620.7 7664.3 7726.8 7728.0 7732.0 7741.1 38358.4 38577.4 38594.8 38602.0 38712.0 3133.4 3199.8 3257.6 3336.7 3559.4 29068.0 29368.9 29552.6 29746.9 29865.6 3550.4 3649.9 3718.5 3719.5 3771.8 253641.0 255971.0 257144.0 258499.0 258917.0 30962.7 31885.4 47481.3 47662.0 47709.0 34298.0 34521.5 34602.8 34633.8 34637.8 12375.0 12475.8 12490.7 12509.4 12523.6 10030.4 10143.1 10143.5 10148.8 10153.3 7930.2 7953.0 7994.2 8030.4 8063.0 3130.3 3131.7 3134.8 3138.5 3173.1 814.4 866.1 875.3 925.9 991.0 27179.2 27490.7 27840.3 28313.3 28751.6 969.0 977.9 1012.4 1015.9 1037.9 14593.7 14737.3 14846.9 14876.1 15026.1 382018.0 385379.0 385780.0 386964.0 387242.0 49775.5 49792.5 50044.2 50432.2 50595.0 1091.9 1118.8 1189.2 1190.3 1218.1 26554.5 28802.9 29690.8 29868.7 29937.0 41693.6 42277.6 42277.8 42278.2 42310.8 319066.0 319496.0 320360.0 321219.0 321597.0 22997.2 25540.7 39491.6 40461.0 40976.4 39627.8 43146.4 43488.0 43848.6 44933.0 36284.5 36981.3 37057.0 37454.0 37584.6 36006.1 36236.6 36495.1 36795.6 37531.4 36866.8 37375.3 37853.2 37904.6 37934.6 31934.8 33522.3 33822.5 33886.7 33931.0 25527.0 25625.2 25814.4 25832.0 26240.7 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | [#2] jquery.html Cycles:180 Average:23645.56 Median:2285.42 stddev:61715.83 (2700.4%) stddev-sans-first:61864.18 16:47:22 INFO - PROCESS | 17461 | Values: 350.9 363.4 364.7 368.1 377.7 16161.0 17201.9 17626.6 17659.6 17729.6 16660.8 17432.5 17693.6 17834.6 17854.4 4895.7 5121.9 5124.5 5124.6 5130.0 3767.3 3947.5 5115.5 5742.5 5973.8 6085.5 6086.9 6090.2 6092.1 6101.7 5842.4 6009.9 6023.2 6026.1 6031.7 4672.3 5218.8 5229.2 5240.8 5256.7 19366.9 20990.0 21135.4 21138.4 21304.4 1783.9 1858.8 1876.9 1885.0 1946.5 8871.8 8878.5 8987.4 9343.2 9555.9 1702.2 1740.7 1789.3 1799.5 1812.6 66458.0 69604.7 70374.3 71281.6 74223.6 9260.9 9285.5 9350.8 9526.3 9529.3 12471.7 12544.2 12631.2 12732.6 12771.6 6474.0 6518.6 6908.4 7041.4 7060.7 6539.5 6608.5 6776.7 6781.9 6805.6 4062.4 4156.5 4207.6 4231.8 4342.2 1582.3 1583.7 1677.6 1737.8 1768.2 377.5 885.6 945.8 960.2 969.8 10094.3 10807.5 10940.5 10952.9 11156.2 396.6 402.8 403.9 412.8 414.3 5553.9 5744.3 5823.2 6055.7 6082.8 353435.0 356694.0 374990.5 380895.0 390043.0 78713.6 79428.0 80518.0 80532.2 80795.0 421.3 429.8 430.3 430.3 431.1 9961.2 10065.5 10204.4 10257.9 10313.6 39103.8 39748.8 39985.0 40299.4 40331.4 51408.2 53908.7 54405.2 54763.7 56028.4 7286.9 7589.1 7633.8 7643.7 7656.7 8705.4 11286.5 11736.8 11827.2 12039.8 9893.6 10135.7 10145.0 10288.7 10790.8 10881.7 10957.5 10961.3 11169.7 11203.8 10725.1 11109.6 11251.6 11254.2 11324.3 7674.9 7956.7 8092.1 8174.6 8214.1 10127.2 10589.2 10923.8 10990.6 11296.5 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | [#3] mootools.html Cycles:180 Average:5086.32 Median:6255.41 stddev:2818.88 (45.1%) stddev-sans-first:2812.97 16:47:22 INFO - PROCESS | 17461 | Values: 1372.0 1418.9 1489.6 1649.4 1726.7 6481.2 6659.8 7246.1 7799.6 7800.6 7079.2 7331.8 7347.6 7536.7 7541.1 2188.9 2201.8 2213.1 2215.7 2217.5 1765.1 2087.4 2114.2 2148.2 2155.5 2217.0 2252.0 2252.5 2253.2 2256.4 2031.3 2057.3 2188.8 2192.6 2193.5 2145.7 2157.2 2166.4 2166.6 2169.2 7922.5 7998.6 8003.6 8014.2 8014.8 4381.6 4492.1 4538.8 4539.9 4548.0 2774.5 2877.4 2886.8 2923.3 2936.3 876.4 925.2 927.0 932.8 934.4 9935.2 9935.4 9935.7 9938.2 9940.7 3720.8 4005.5 4154.1 4154.4 4158.0 8602.9 8631.7 8657.0 8674.5 8681.3 4455.8 4493.6 4497.0 4499.6 4500.2 4042.1 4071.9 4077.2 4080.4 4083.0 3563.8 3665.3 3689.0 3694.6 3698.7 991.5 1080.1 1100.8 1134.8 1138.2 805.1 833.0 837.7 839.4 850.1 5077.1 5561.6 5663.6 5761.6 5841.4 874.2 893.8 897.2 899.8 906.7 3709.4 3789.7 3817.3 3819.1 3851.7 9543.5 9789.6 9874.7 9880.5 9881.0 9686.6 9768.1 9777.0 9781.0 9797.7 6901.2 7019.5 7026.7 7028.1 7029.9 4965.1 5012.7 5028.5 5119.2 5200.9 8618.2 8887.8 8918.9 9099.4 9123.5 8959.1 9013.0 9113.4 9143.7 9213.5 6396.2 6487.3 6500.1 6548.6 6558.5 7536.8 7537.8 7561.9 7569.0 7574.1 6448.7 6637.7 6687.1 6781.4 6842.5 6416.3 6558.7 6663.7 6932.8 6982.5 6218.6 6246.8 6264.0 6291.5 6301.5 4513.7 4526.3 4645.2 4733.8 4790.6 4701.8 4740.3 4954.3 5014.6 5023.6 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | [#4] prototype.html Cycles:180 Average:4081.64 Median:4420.12 stddev:1164.76 (26.4%) stddev-sans-first:1132.61 16:47:22 INFO - PROCESS | 17461 | Values: 284.0 299.3 303.4 304.4 308.3 4665.7 4782.3 4822.0 4853.6 4863.5 4614.5 4762.3 4764.2 4771.3 4788.4 3802.6 3809.7 3818.4 3825.8 3836.2 3525.8 3833.5 3834.3 3838.3 3847.6 3598.4 3733.3 3755.3 3774.4 3784.5 3712.4 3751.7 3753.8 3754.9 3756.2 3325.6 3329.9 3330.1 3330.3 3333.6 4519.6 4669.3 4673.8 4701.5 4735.1 4256.6 4269.1 4274.9 4287.9 4293.5 4967.6 4992.1 4994.3 4996.0 5013.4 3559.2 3661.6 3679.3 3684.7 3699.8 5184.6 5263.1 5270.8 5276.4 5280.5 3777.0 3786.4 3789.8 3796.9 3817.4 3726.6 3866.6 3876.4 3880.7 3881.6 4351.7 4352.2 4352.6 4354.0 4384.2 5066.3 5067.5 5077.9 5092.5 5099.3 1185.5 1258.0 1333.9 1344.8 1348.5 920.1 931.9 934.1 934.6 936.2 4418.6 4420.4 4421.3 4430.9 4441.1 5152.2 5160.0 5167.9 5173.2 5193.9 4269.8 4404.5 4414.5 4419.8 4435.1 2419.6 2425.6 2436.7 2439.7 2444.4 4287.3 4324.5 4334.1 4350.4 4366.1 4463.8 4608.5 4627.0 4645.9 4667.3 4267.0 4320.1 4320.4 4323.8 4328.4 4556.9 4562.0 4564.0 4570.9 4579.7 4295.9 4445.5 4482.8 4491.5 4509.6 4253.9 4264.4 4303.0 4316.6 4329.7 3413.8 3446.9 3447.9 3452.0 3585.0 5004.1 5166.8 5184.9 5191.2 5198.2 5003.6 5012.2 5031.3 5033.3 5086.5 4876.4 5035.9 5053.1 5054.5 5058.8 4878.0 4912.7 4927.7 4936.6 4952.9 5034.8 5051.8 5082.3 5088.2 5103.8 4807.0 4934.9 4935.1 4966.9 4981.2 16:47:22 INFO - PROCESS | 17461 | 16:47:22 INFO - PROCESS | 17461 | [#5] yui.html Cycles:180 Average:17343.37 Median:516.98 stddev:24809.11 (4798.8%) stddev-sans-first:24846.98 16:47:22 INFO - PROCESS | 17461 | Values: 635.5 636.4 640.5 641.1 693.2 12990.2 13322.3 14053.2 14128.0 14151.5 13433.3 13647.6 13660.9 13699.0 13702.2 643.7 772.3 782.2 791.0 803.3 786.7 792.2 792.3 792.3 792.4 828.0 828.3 828.9 829.7 829.7 660.4 807.8 821.3 821.6 824.3 607.1 607.3 607.3 607.8 607.8 12698.4 12705.9 12720.8 12751.4 12760.5 12221.0 12559.8 12921.9 13154.3 13582.4 9352.2 9576.3 10358.0 10416.2 10576.7 4081.5 4082.2 4104.7 4169.3 4302.7 61611.7 61961.4 62313.4 63543.4 63589.0 9503.1 9672.7 9701.2 9794.4 9835.4 17925.6 17934.6 18112.9 18114.7 18119.7 6383.1 6507.6 6518.5 6598.4 6633.9 3758.1 3787.1 3805.6 3884.8 3885.6 2737.7 2823.4 2826.4 2841.8 2869.7 488.4 492.7 495.1 511.7 511.9 485.2 489.2 509.2 511.2 549.3 8253.3 8318.6 8418.9 8485.3 8722.7 515.8 518.2 526.6 542.2 633.7 4047.4 4063.6 4152.4 4157.6 4321.1 58138.0 77562.6 81802.8 106050.5 106421.0 59508.0 87742.6 91974.5 92063.1 92467.5 47716.3 56962.1 73602.0 74504.0 77461.6 7100.4 7129.5 7135.2 7311.2 7443.5 46114.5 46719.0 47810.3 48178.8 48872.0 64961.0 69709.3 70306.0 70442.6 73293.0 10482.7 10504.5 10535.4 10621.0 10647.9 10703.9 10749.6 10905.5 11931.1 12272.7 10122.0 10912.5 10995.3 11033.8 11574.7 10875.7 11036.4 11298.3 11316.8 11485.4 10991.8 11216.8 11485.7 11850.5 11955.8 9050.5 9068.9 9345.2 9389.3 9443.3 8297.3 8785.3 9242.3 9424.2 9438.5 16:47:22 INFO - PROCESS | 17461 | -------- Summary: end -------- 16:47:22 INFO - PROCESS | 17461 | 16:47:23 INFO - TEST-INFO | 17461: exit 0 16:47:23 INFO - TEST-OK | dromaeo_css | took 1120355ms 16:47:23 INFO - TEST-START | kraken 16:47:23 INFO - operating with platform_type : linux_ 16:47:23 INFO - Initialising browser for kraken test... 16:47:23 INFO - TEST-INFO | started process 17653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcQAJ7D/profile http://localhost:47960/getInfo.html) 16:47:27 INFO - PROCESS | 17653 | __metrics Screen width/height:1600/1200 16:47:27 INFO - PROCESS | 17653 | colorDepth:24 16:47:27 INFO - PROCESS | 17653 | Browser inner width/height: 1024/697 16:47:27 INFO - PROCESS | 17653 | __metrics 16:47:28 INFO - PROCESS | 17653 | 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" 16:47:28 INFO - PROCESS | 17653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:47:29 INFO - TEST-INFO | 17653: exit 0 16:47:29 INFO - Browser initialized. 16:47:29 INFO - Running cycle 1/1 for kraken test... 16:47:29 INFO - TEST-INFO | started process 17737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcQAJ7D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:47:41 INFO - PROCESS | 17737 | RSS: Main: 157552640 16:47:41 INFO - PROCESS | 17737 | 16:47:44 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47960/tests/kraken/driver.html?testName=audio-beat-detection) 16:47:45 INFO - PROCESS | 17737 | RSS: Main: 212905984 16:47:45 INFO - PROCESS | 17737 | 16:47:47 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47960/tests/kraken/driver.html?testName=audio-dft) 16:47:48 INFO - PROCESS | 17737 | RSS: Main: 192565248 16:47:48 INFO - PROCESS | 17737 | 16:47:51 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47960/tests/kraken/driver.html?testName=audio-fft) 16:47:52 INFO - PROCESS | 17737 | RSS: Main: 271560704 16:47:52 INFO - PROCESS | 17737 | 16:47:54 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47960/tests/kraken/driver.html?testName=audio-oscillator) 16:47:55 INFO - PROCESS | 17737 | RSS: Main: 180310016 16:47:55 INFO - PROCESS | 17737 | 16:47:57 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47960/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:47:58 INFO - PROCESS | 17737 | RSS: Main: 184684544 16:47:58 INFO - PROCESS | 17737 | 16:48:01 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47960/tests/kraken/driver.html?testName=imaging-darkroom) 16:48:01 INFO - PROCESS | 17737 | RSS: Main: 221380608 16:48:01 INFO - PROCESS | 17737 | 16:48:05 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47960/tests/kraken/driver.html?testName=imaging-desaturate) 16:48:06 INFO - PROCESS | 17737 | RSS: Main: 215552000 16:48:06 INFO - PROCESS | 17737 | 16:48:08 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47960/tests/kraken/driver.html?testName=json-parse-financial) 16:48:09 INFO - PROCESS | 17737 | RSS: Main: 218488832 16:48:09 INFO - PROCESS | 17737 | 16:48:11 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47960/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:48:11 INFO - PROCESS | 17737 | RSS: Main: 184164352 16:48:11 INFO - PROCESS | 17737 | 16:48:13 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:48:14 INFO - PROCESS | 17737 | RSS: Main: 188805120 16:48:14 INFO - PROCESS | 17737 | 16:48:16 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:48:17 INFO - PROCESS | 17737 | RSS: Main: 194678784 16:48:17 INFO - PROCESS | 17737 | 16:48:20 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:48:20 INFO - PROCESS | 17737 | RSS: Main: 194670592 16:48:20 INFO - PROCESS | 17737 | 16:48:23 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:48:24 INFO - PROCESS | 17737 | RSS: Main: 195891200 16:48:24 INFO - PROCESS | 17737 | 16:48:25 INFO - PROCESS | 17737 | Cycle 1(1): loaded http://localhost:47960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47960/tests/kraken/driver.html?testName=ai-astar) 16:48:26 INFO - PROCESS | 17737 | RSS: Main: 242786304 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | __start_tp_report 16:48:26 INFO - PROCESS | 17737 | _x_x_mozilla_page_load 16:48:26 INFO - PROCESS | 17737 | _x_x_mozilla_page_load_details 16:48:26 INFO - PROCESS | 17737 | |i|pagename|runs| 16:48:26 INFO - PROCESS | 17737 | |0;ai-astar;104;114;114;116;114;113;113;113;115;113 16:48:26 INFO - PROCESS | 17737 | |1;audio-beat-detection;152;128;125;210;124;125;149;122;188;122 16:48:26 INFO - PROCESS | 17737 | |2;audio-dft;164;161;164;161;162;162;163;165;164;160 16:48:26 INFO - PROCESS | 17737 | |3;audio-fft;76;76;75;100;76;76;75;75;111;78 16:48:26 INFO - PROCESS | 17737 | |4;audio-oscillator;85;85;129;86;85;85;134;84;87;126 16:48:26 INFO - PROCESS | 17737 | |5;imaging-gaussian-blur;117;117;117;117;126;117;117;117;116;117 16:48:26 INFO - PROCESS | 17737 | |6;imaging-darkroom;146;146;146;145;146;145;146;146;147;147 16:48:26 INFO - PROCESS | 17737 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;94;94 16:48:26 INFO - PROCESS | 17737 | |8;json-parse-financial;64;62;61;62;63;65;64;63;63;63 16:48:26 INFO - PROCESS | 17737 | |9;json-stringify-tinderbox;64;65;64;65;65;66;65;65;66;65 16:48:26 INFO - PROCESS | 17737 | |10;stanford-crypto-aes;80;79;79;89;80;79;79;79;78;83 16:48:26 INFO - PROCESS | 17737 | |11;stanford-crypto-ccm;136;136;133;140;138;133;129;129;133;141 16:48:26 INFO - PROCESS | 17737 | |12;stanford-crypto-pbkdf2;167;165;167;165;167;163;164;163;162;168 16:48:26 INFO - PROCESS | 17737 | |13;stanford-crypto-sha256-iterative;67;70;78;66;68;68;67;66;66;67 16:48:26 INFO - PROCESS | 17737 | __end_tp_report 16:48:26 INFO - PROCESS | 17737 | __start_cc_report 16:48:26 INFO - PROCESS | 17737 | _x_x_mozilla_cycle_collect,1961 16:48:26 INFO - PROCESS | 17737 | __end_cc_report 16:48:26 INFO - PROCESS | 17737 | __startTimestamp1470613706181__endTimestamp 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | ------- Summary: start ------- 16:48:26 INFO - PROCESS | 17737 | Number of tests: 14 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.28 (2.9%) stddev-sans-first:1.05 16:48:26 INFO - PROCESS | 17737 | Values: 104.0 114.0 114.0 116.0 114.0 113.0 113.0 113.0 115.0 113.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:138.50 stddev:31.14 (22.5%) stddev-sans-first:32.91 16:48:26 INFO - PROCESS | 17737 | Values: 152.0 128.0 125.0 210.0 124.0 125.0 149.0 122.0 188.0 122.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.50 stddev:1.65 (1.0%) stddev-sans-first:1.67 16:48:26 INFO - PROCESS | 17737 | Values: 164.0 161.0 164.0 161.0 162.0 162.0 163.0 165.0 164.0 160.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:12.79 (16.8%) stddev-sans-first:13.39 16:48:26 INFO - PROCESS | 17737 | Values: 76.0 76.0 75.0 100.0 76.0 76.0 75.0 75.0 111.0 78.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:85.00 stddev:21.54 (25.3%) stddev-sans-first:22.27 16:48:26 INFO - PROCESS | 17737 | Values: 85.0 85.0 129.0 86.0 85.0 85.0 134.0 84.0 87.0 126.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:117.00 stddev:2.90 (2.5%) stddev-sans-first:3.06 16:48:26 INFO - PROCESS | 17737 | Values: 117.0 117.0 117.0 117.0 126.0 117.0 117.0 117.0 116.0 117.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 16:48:26 INFO - PROCESS | 17737 | Values: 146.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 147.0 147.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:48:26 INFO - PROCESS | 17737 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 94.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:1.15 (1.8%) stddev-sans-first:1.17 16:48:26 INFO - PROCESS | 17737 | Values: 64.0 62.0 61.0 62.0 63.0 65.0 64.0 63.0 63.0 63.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 16:48:26 INFO - PROCESS | 17737 | Values: 64.0 65.0 64.0 65.0 65.0 66.0 65.0 65.0 66.0 65.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:79.50 stddev:3.27 (4.1%) stddev-sans-first:3.47 16:48:26 INFO - PROCESS | 17737 | Values: 80.0 79.0 79.0 89.0 80.0 79.0 79.0 79.0 78.0 83.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#11] stanford-crypto-ccm Cycles:10 Average:134.80 Median:136.00 stddev:4.16 (3.1%) stddev-sans-first:4.39 16:48:26 INFO - PROCESS | 17737 | Values: 136.0 136.0 133.0 140.0 138.0 133.0 129.0 129.0 133.0 141.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:2.08 (1.3%) stddev-sans-first:2.09 16:48:26 INFO - PROCESS | 17737 | Values: 167.0 165.0 167.0 165.0 167.0 163.0 164.0 163.0 162.0 168.0 16:48:26 INFO - PROCESS | 17737 | 16:48:26 INFO - PROCESS | 17737 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:3.62 (5.4%) stddev-sans-first:3.81 16:48:26 INFO - PROCESS | 17737 | Values: 67.0 70.0 78.0 66.0 68.0 68.0 67.0 66.0 66.0 67.0 16:48:26 INFO - PROCESS | 17737 | -------- Summary: end -------- 16:48:26 INFO - PROCESS | 17737 | 16:48:27 INFO - TEST-INFO | 17737: exit 0 16:48:27 INFO - TEST-OK | kraken | took 63955ms 16:48:27 INFO - SUITE-END | took 1184s 16:48:27 INFO - Completed test suite (00:19:44) 16:48:27 INFO - javascript benchmark 16:48:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2658.388570628755, 2673.5588627876377, 2701.5542435498137, 2708.377073273146, 2712.7422894825, 4600.033992861495, 4666.160091183586, 4787.090452813967, 4926.06404783091, 4941.752912354382, 4774.522547745224, 4806.038792241548, 4820.108279968208, 4833.492483289257, 4851.805927762885, 4285.871423857274, 4330.155619654177, 4414.690971631987, 4464.776761161945, 4596.034832685124, 4515.051839113774, 4546.181687296285, 4570.9314360284625, 4589.518100599439, 4591.5867571918525, 4222.4299719538085, 4257.914841703179, 4459.710118842265, 4472.84345047925, 4563.703359281652, 4320.027993701424, 4329.328954012133, 4392.3411488277015, 4418.425604671372, 4449.021215332622, 4271.914561708749, 4275.123599662062, 4328.480582330109, 4331.588499092612, 4481.5070342262325, 4727.527247275245, 4755.286706993944, 4786.928196077061, 4792.065547218293, 4799.880002999918, 4974.228994505857, 5050.84847454577, 5074.695518268915, 5107.055194788976, 5122.795088196468, 5247.055530004598, 5257.1588545832865, 5257.553107985787, 5283.0, 5314.707691076989, 4833.130036593447, 4837.854864354075, 4856.028794241166, 4860.80556777732, 4947.084789313988, 5038.622103342264, 5193.76628051731, 5226.764795584208, 5227.869303267449, 5403.9459605403445, 5058.64589478733, 5092.10888094582, 5107.846764597068, 5109.0, 5115.948840511547, 5135.974320128375, 5136.383633963948, 5139.254808052811, 5185.962807438527, 5187.195984622314, 4763.666543342002, 4884.5603895649565, 5003.624728145331, 5017.12200364942, 5018.322526458955, 5036.445990940993, 5036.471170527115, 5039.571636410873, 5041.974790126099, 5081.2378143278065, 4328.458942632171, 4331.588499092644, 4335.479742430928, 4365.345198220292, 4372.519022907478, 3726.571444283942, 3728.0307120148955, 3754.099016236138, 3762.0406796266775, 3782.149016471272, 4354.586314300136, 4355.303151495746, 4375.146846364928, 4385.890352741207, 4398.846040388571, 5121.513456221653, 5123.257127716461, 5124.66689665178, 5125.846224613268, 5171.741412929338, 4211.052513184509, 4336.848210312624, 4377.343398490252, 4407.867763967086, 4464.598186163261, 4693.319468677023, 4707.058588282357, 4714.976425117852, 4745.6440766942615, 4754.191787396151, 4932.630052745986, 4937.40751109862, 4941.061198372297, 4941.851744447672, 4962.826301079445, 4915.11527924977, 4920.0, 5049.697018178921, 5273.209018647233, 5330.333708286465, 4822.903541929071, 4847.587955023862, 4865.440474345495, 4887.511248875084, 4901.681390709592, 5054.342935418372, 5058.797648094035, 5066.392032956069, 5083.415407228216, 5107.284980102863, 4951.752412379438, 5126.077306084941, 5157.458466860926, 5172.896542069062, 5175.508326708957, 5002.87492812668, 5063.822766203165, 5069.340985671839, 5111.974440127776, 5122.615803814654, 5041.218611115283, 5041.294218809297, 5044.192929131468, 5058.367704036996, 5102.974485127699, 4755.310479980314, 4928.2853986171085, 4979.9004019918675, 4989.750512474435, 5042.873928151826, 5049.924251136309, 5079.746012699424, 5091.312672789126, 5138.07514647367, 5141.614378921521, 4963.875903102451, 4986.0, 4993.80024799004, 5015.222640490584, 5057.848264552069], "value": 4718.357377175768, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.1878479521068, 595.7289433307845, 602.3795490644637, 613.0742578706153, 627.1188979483827, 28260.43479130416, 31739.682603173962, 39011.80494097529, 40375.0, 40531.594684053154, 40178.7991060045, 40398.59601403985, 40424.39363409544, 40616.390754138636, 40641.390379144264, 7482.438817088727, 7640.274173953493, 7641.930129781835, 7644.732434364799, 7672.46292759507, 7486.887696684554, 7589.241075892422, 7592.848143037136, 7597.81005474862, 7598.9620051899665, 7593.05086864142, 7630.580318082503, 7631.503952243114, 7633.961830190841, 7643.197464266255, 7582.734604288851, 7603.163651998276, 7616.238376162395, 7618.085829700416, 7620.733274335427, 7664.31021208094, 7726.7681969541, 7728.033995750531, 7731.961340193263, 7741.109772376192, 38358.424623630664, 38577.421338679946, 38594.80702596497, 38602.0, 38712.0, 3133.3576616793503, 3199.8240096794666, 3257.5765150530515, 3336.6663333666684, 3559.4126969050335, 29068.0, 29368.853155734083, 29552.556711649344, 29746.85126574353, 29865.55201671977, 3550.431930891045, 3649.8540058397634, 3718.51659284294, 3719.460678201646, 3771.830267637963, 253641.0, 255971.0, 257144.0, 258499.0, 258917.0, 30962.69037309643, 31885.362292754016, 47481.28778068332, 47662.0, 47709.0, 34298.0, 34521.48217776736, 34602.82698586542, 34633.826830865684, 34637.82681086579, 12375.0, 12475.812862807064, 12490.687732806753, 12509.437075331633, 12523.56167534132, 10030.448325342177, 10143.08712215904, 10143.543540540693, 10148.797024059477, 10153.340032897839, 7930.246626570467, 7953.045634523778, 7994.160613135653, 8030.397720170894, 8062.959685201537, 3130.2800355918266, 3131.7024882636115, 3134.843257837099, 3138.466460701686, 3173.1273899677312, 814.4136221920209, 866.1165611076666, 875.3434923807145, 925.8842644669417, 990.9752256193652, 27179.184624461297, 27490.725092748817, 27840.303992399946, 28313.292167695974, 28751.56872646912, 968.9806203875883, 977.9168770654431, 1012.4482157224184, 1015.8882522922472, 1037.8754549454113, 14593.708125837424, 14737.263136843116, 14846.92576537132, 14876.107433553805, 15026.098434093989, 382018.0, 385379.0, 385780.0, 386964.0, 387242.0, 49775.502244977084, 49792.50207497951, 50044.249336259985, 50432.243516347284, 50595.0, 1091.8853520380405, 1118.7594667146602, 1189.232944750631, 1190.33936165429, 1218.0620921890272, 26554.468910622065, 28802.855985719936, 29690.758684002936, 29868.70131298746, 29937.0, 41693.58306417018, 42277.57722422737, 42277.78861105675, 42278.154436911704, 42310.78844605757, 319066.0, 319496.0, 320360.0, 321219.0, 321597.0, 22997.195098171484, 25540.744592553838, 39491.605083948794, 40461.0, 40976.385354219114, 39627.80186099166, 43146.35280470733, 43488.0, 43848.56151438445, 44933.0, 36284.45573316455, 36981.260374792895, 37057.0, 37454.0, 37584.624153758115, 36006.099847504025, 36236.63763362333, 36495.08762280964, 36795.63204367922, 37531.43702844514, 36866.8156659215, 37375.2524949494, 37853.242935141694, 37904.620953791215, 37934.62065379311, 31934.84032579822, 33522.32955340928, 33822.49266261057, 33886.66113338835, 33931.0, 25527.0, 25625.23124306274, 25814.354641134123, 25832.0, 26240.73759262383], "value": 16556.37394310333, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.85790254946755, 363.3985753577828, 364.7446787248926, 368.0614433195351, 377.7392307042351, 16161.0, 17201.913990430046, 17626.559336016606, 17659.55851103723, 17729.64540709185, 16660.75008874868, 17432.477025689248, 17693.64612707745, 17834.55413614657, 17854.375096871612, 4895.730734809576, 5121.9487805122035, 5124.538791508763, 5124.564412024983, 5130.0, 3767.284215998965, 3947.54603220629, 5115.488451154892, 5742.454466825666, 5973.790917317895, 6085.543584231178, 6086.939130608704, 6090.208272924536, 6092.08618707193, 6101.694915254242, 5842.386549412315, 6009.939900600982, 6023.156758053876, 6026.06595977622, 6031.668258245794, 4672.299155126724, 5218.817341393033, 5229.2156176573435, 5240.764165612557, 5256.737163141827, 19366.903165484222, 20990.0, 21135.365939021853, 21138.365849024554, 21304.36086917395, 1783.9197236124405, 1858.8419984301352, 1876.8967706776123, 1884.9717254241198, 1946.4939115829986, 8871.778205544913, 8878.467291962503, 8987.41581797181, 9343.205827504671, 9555.904440955572, 1702.1829521829468, 1740.7301868210448, 1789.3021721528614, 1799.4601619514094, 1812.619349936515, 66458.0, 69604.6519767403, 70374.29625703729, 71281.64359178222, 74223.62888185578, 9260.907390926004, 9285.535723213812, 9350.784398028212, 9526.285528585384, 9529.332946693665, 12471.688207794696, 12544.184627999151, 12631.178973366701, 12732.554360597334, 12771.552995645108, 6473.967630161819, 6518.608883467007, 6908.447324214051, 7041.366277035091, 7060.71757129709, 6539.50953678476, 6608.537402381883, 6776.661166941732, 6781.93218067813, 6805.557638753465, 4062.370332598452, 4156.501219853576, 4207.579242075829, 4231.767252801125, 4342.240107981091, 1582.311694412911, 1583.6515966487354, 1677.6057626457773, 1737.8435940765391, 1768.2396569474993, 377.49981274810983, 885.6413152673177, 945.7635591102224, 960.2126256469628, 969.8351280282369, 10094.343867648578, 10807.513661885234, 10940.45297735126, 10952.945235273774, 11156.219064665396, 396.64896566538823, 402.8287977609504, 403.8869116647343, 412.83899279281104, 414.3411974959768, 5553.944460555424, 5744.253247077853, 5823.242978412779, 6055.697215139226, 6082.847928801727, 353435.0, 356694.0, 374990.5009499028, 380895.0, 390043.0, 78713.60643196861, 79428.0, 80518.0, 80532.19467805364, 80795.0, 421.3279818689186, 429.810883211386, 430.26424813568525, 430.3179460554993, 431.0516862901629, 9961.153301969414, 10065.547050382896, 10204.38773673582, 10257.897421025695, 10313.587456501657, 39103.804480977415, 39748.80125599354, 39985.0, 40299.3955090668, 40331.395029075175, 51408.228876566136, 53908.73045634747, 54405.18392224041, 54763.72618136884, 56028.43971560232, 7286.890696639449, 7589.1272503662785, 7633.770986870402, 7643.694252230071, 7656.73201437947, 8705.434146780439, 11286.548538058385, 11736.823947640622, 11827.231229970024, 12039.819402708792, 9893.604255829687, 10135.7466063349, 10144.985501449795, 10288.691339259834, 10790.784184316426, 10881.673549793519, 10957.452127393757, 10961.342319460859, 11169.664910052712, 11203.775924481628, 10725.141988640735, 11109.611163609236, 11251.606193783178, 11254.155938304824, 11324.32054076758, 7674.9232507674205, 7956.681732730626, 8092.069412017458, 8174.550399728072, 8214.096449390443, 10127.189824814144, 10589.152867770406, 10923.836142458029, 10990.560377584807, 11296.49165787525], "value": 7582.572642962701, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.9862801371983, 1418.8864890808734, 1489.5605796290092, 1649.381481944271, 1726.6719323328566, 6481.157449531566, 6659.766908158215, 7246.130464344279, 7799.649015794289, 7800.570968596725, 7079.150501939775, 7331.816704582388, 7347.559146451216, 7536.698532058725, 7541.057367829021, 2188.8796116213603, 2201.8348623853194, 2213.0815711479718, 2215.700880381144, 2217.523232505011, 1765.0556952030665, 2087.3737878636425, 2114.164904862578, 2148.1729534129317, 2155.4503601581573, 2216.9603964352755, 2251.966220506694, 2252.549490101978, 2253.1663284584724, 2256.4133325335447, 2031.2687432524278, 2057.32108404227, 2188.8139508141826, 2192.6053310404122, 2193.451637090727, 2145.731783527059, 2157.169489746443, 2166.393409845246, 2166.6425039868404, 2169.1648715244746, 7922.485038472539, 7998.64006119726, 8003.559804210766, 8014.238647328494, 8014.759557213293, 4381.605655491021, 4492.056668099703, 4538.7503687297185, 4539.931901021488, 4548.0221752323405, 2774.4589804988036, 2877.438899414615, 2886.7546258568045, 2923.283795470102, 2936.3393236521824, 876.4040452492303, 925.2459245714788, 926.9814603707955, 932.8460803967337, 934.3552948465536, 9935.155511781508, 9935.403875767477, 9935.65225217114, 9938.204943604494, 9940.701778946643, 3720.8325625346924, 4005.499312585927, 4154.065335299594, 4154.439150714676, 4158.0, 8602.87095693565, 8631.6978905738, 8657.0, 8674.479531228146, 8681.348898832613, 4455.844045458393, 4493.618042466427, 4496.955030449719, 4499.595036446735, 4500.167469018242, 4042.1348731942167, 4071.898202544901, 4077.245709543744, 4080.4491393661474, 4082.9996650820144, 3563.821808909596, 3665.2669466106886, 3689.0408493791806, 3694.6305369463375, 3698.6671199592165, 991.494337887668, 1080.0873262093519, 1100.7963526747578, 1134.8241022641503, 1138.1520767028617, 805.0822062848357, 832.9791755206169, 837.7026155714756, 839.366278459759, 850.1498501498502, 5077.086124497552, 5561.63849349791, 5663.631863928831, 5761.625494342854, 5841.386654401312, 874.2437791310471, 893.8033632600817, 897.2059727141527, 899.7615631857562, 906.7370462565914, 3709.4250391189407, 3789.734718569729, 3817.312883680964, 3819.1216020315483, 3851.730378873508, 9543.522823858779, 9789.608415663293, 9874.703758887244, 9880.505974701236, 9881.0, 9686.564104615169, 9768.120869121813, 9777.0, 9781.0, 9797.706068817804, 6901.171859376806, 7019.543729657556, 7026.718931242795, 7028.121484814398, 7029.92970070303, 4965.106280869478, 5012.699238045583, 5028.547430731252, 5119.206522988943, 5200.869978250423, 8618.181272779077, 8887.777805554913, 8918.910810891808, 9099.408538444979, 9123.452592844451, 8959.148880856308, 9013.0, 9113.407628504126, 9143.679971200976, 9213.539323033956, 6396.200474940632, 6487.286398496068, 6500.122483464672, 6548.574342667712, 6558.475321974326, 7536.773896783106, 7537.773866784005, 7561.886571701539, 7569.0, 7574.053243344582, 6448.742050318122, 6637.734490620321, 6687.063811066358, 6781.355771201728, 6842.521023528305, 6416.294207637063, 6558.672066396756, 6663.666816659244, 6932.792016239521, 6982.476314276348, 6218.5647004711045, 6246.812595622138, 6264.0, 6291.528135389773, 6301.464375528131, 4513.729176249435, 4526.321051842118, 4645.163870503342, 4733.83431579893, 4790.568848803624, 4701.811927522997, 4740.265258884879, 4954.256861470808, 5014.598832093497, 5023.598112150946], "value": 4109.733936098341, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.968763436022, 299.3280086206467, 303.4219811259551, 304.4018503640346, 308.33861367367, 4665.673402861801, 4782.330473733676, 4821.975890120553, 4853.563179313861, 4863.465018847928, 4614.538546145383, 4762.309465127554, 4764.190087685093, 4771.284307353899, 4788.40869347989, 3802.638749318824, 3809.657130858222, 3818.3508803503332, 3825.7810469927513, 3836.213576216876, 3525.7708248963863, 3833.5016447861876, 3834.328992426328, 3838.309104361214, 3847.557530883942, 3598.352296586613, 3733.290674771785, 3755.324041672498, 3774.433834924756, 3784.5080139581955, 3712.406015037581, 3751.6985053153053, 3753.7935413552245, 3754.8923067695096, 3756.192418629994, 3325.567676202078, 3329.916752081193, 3330.134165117087, 3330.2516179005975, 3333.599968003831, 4519.615832654228, 4669.34629151919, 4673.836415725467, 4701.48283688794, 4735.076660051292, 4256.574342565718, 4269.124829409963, 4274.871753847358, 4287.871363859089, 4293.506246781598, 4967.602591792648, 4992.101421744084, 4994.275830004629, 4995.9500404996215, 5013.448520662724, 3559.2347645256395, 3661.5972243053243, 3679.3193259247123, 3684.6683798458266, 3699.7780133191827, 5184.559312458446, 5263.0526505229045, 5270.7628156733035, 5276.419593844674, 5280.49835265653, 3776.962230377661, 3786.4320351947426, 3789.7915614640906, 3796.9430458543143, 3817.351050321452, 3726.6273372662517, 3866.6326698963553, 3876.4185372194397, 3880.670143037817, 3881.5536213335827, 4351.717138385995, 4352.173087113439, 4352.586504282088, 4354.0, 4384.188925048876, 5066.290719299301, 5067.5185857343495, 5077.873053173626, 5092.541671249605, 5099.260607212008, 1185.5376403202758, 1257.9685507862193, 1333.8866196373224, 1344.8318960129984, 1348.4673553946136, 920.1488623023677, 931.937591146094, 934.1265916368218, 934.6308208257836, 936.194872409727, 4418.646508279394, 4420.447444069491, 4421.314696222091, 4430.933535996963, 4441.111777644484, 5152.175651895754, 5160.0, 5167.896642067213, 5173.198154286091, 5193.922091168635, 4269.825797905539, 4404.4714634243455, 4414.514403415622, 4419.801108950105, 4435.090806384684, 2419.552382809186, 2425.5755242832433, 2436.7075950886006, 2439.7438268981855, 2444.4255599934, 4287.3140297552245, 4324.545922678137, 4334.13317336534, 4350.390945267665, 4366.104948485553, 4463.843765468193, 4608.5161058088415, 4626.976865115653, 4645.883852903705, 4667.346571479996, 4267.015837635515, 4320.114376552862, 4320.4383430153875, 4323.827046918151, 4328.350747387917, 4556.863294101182, 4561.977190114028, 4564.0, 4570.931436028529, 4579.702319349231, 4295.906946998156, 4445.510993790615, 4482.820687172477, 4491.505934347285, 4509.571590698878, 4253.9361909572, 4264.42216379929, 4302.978485107555, 4316.589923957219, 4329.718568293051, 3413.753979797406, 3446.9482957756154, 3447.9482807757404, 3452.016175390002, 3584.964150358463, 5004.099262132851, 5166.793328266827, 5184.948150518598, 5191.16941289407, 5198.2202669599865, 5003.599712023102, 5012.1980483122525, 5031.270465782514, 5033.320501732219, 5086.516780905808, 4876.414830220397, 5035.924461133012, 5053.140966035856, 5054.519820617035, 5058.7976480941825, 4877.975610121926, 4912.680675756064, 4927.679700819435, 4936.605071594336, 4952.900941981212, 5034.802900158353, 5051.772670229922, 5082.288479612783, 5088.1604535252745, 5103.770330335218, 4806.975965120152, 4934.925976110289, 4935.087008903364, 4966.8758281043265, 4981.227909674007], "value": 3720.1644937049637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.4789072960173, 636.4272155060443, 640.5484133685749, 641.1216633212498, 693.1855070292405, 12990.155639883413, 13322.333883305844, 14053.227072511008, 14128.0, 14151.504697335582, 13433.328333583331, 13647.590572282823, 13660.931695341535, 13699.0, 13702.246376449291, 643.671727419015, 772.3473664753275, 782.202153803119, 791.0309870408732, 803.3492870774662, 786.6735304848481, 792.2434075457924, 792.2513225002375, 792.2671528835832, 792.3581898662073, 827.9917200828005, 828.2711214131556, 828.8632375658009, 829.6598394658191, 829.6930135849744, 660.4056349285634, 807.8384323135397, 821.256762629821, 821.5892053973014, 824.2540500846743, 607.0802733858209, 607.2712744706325, 607.3046361915582, 607.781198768443, 607.8389226854887, 12698.365081745877, 12705.936470317682, 12720.809187862189, 12751.426185821567, 12760.489580416772, 12221.0, 12559.811602825965, 12921.870781292255, 13154.342282885817, 13582.388792504362, 9352.158305752515, 9576.329656924017, 10358.041609567708, 10416.166706663449, 10576.735581610445, 4081.5102187737366, 4082.203970225807, 4104.692148088905, 4169.332906734938, 4302.698811083225, 61611.691941540455, 61961.38038619601, 62313.376866231665, 63543.36456635467, 63589.0, 9503.132436742675, 9672.70981870545, 9701.240359386147, 9794.40887581019, 9835.409875407498, 17925.551861203312, 17934.5516362092, 18112.909435452737, 18114.728279075825, 18119.72820407695, 6383.138276332728, 6507.577007494498, 6518.543701940818, 6598.43913267368, 6633.900491492632, 3758.1168425420005, 3787.1289603391374, 3805.5813860475328, 3884.8251828667776, 3885.611438856148, 2737.7262273772462, 2823.364742932865, 2826.3640680846647, 2841.77265818734, 2869.684334723179, 488.36024807502287, 492.6896055485093, 495.1261024292127, 511.6801998750781, 511.9283300337956, 485.24786580800327, 489.18306428264884, 509.162427806264, 511.2433598274574, 549.3380476525776, 8253.33973282148, 8318.625661845232, 8418.873716894252, 8485.278751306209, 8722.738317850475, 515.8039944820944, 518.1657531374484, 526.6339893773791, 542.2219115569171, 633.7179954920108, 4047.372657238133, 4063.6342729154517, 4152.397902304209, 4157.64660003897, 4321.0709697415205, 58138.0, 77562.61218693983, 81802.77465316835, 106050.46974765077, 106421.0, 59508.0, 87742.56128719315, 91974.54012729894, 92063.07936920546, 92467.5376623126, 47716.284255736195, 56962.145567816515, 73602.0, 74504.0, 77461.61269193619, 7100.431965442649, 7129.536580122275, 7135.215126336203, 7311.195768465358, 7443.4789564729945, 46114.538854611026, 46719.0, 47810.28284575804, 48178.75910620424, 48872.0, 64961.0, 69709.30290697028, 70306.0, 70442.64778676073, 73293.0, 10482.737931551772, 10504.527296271839, 10535.420551869414, 10621.0, 10647.94676026615, 10703.946480267548, 10749.62376316825, 10905.454727263765, 11931.105167112675, 12272.711365306513, 10122.0, 10912.454377281263, 10995.285306455055, 11033.834492482458, 11574.71063223426, 10875.673729788119, 11036.448177590928, 11298.3496904425, 11316.830247546459, 11485.368304743319, 10991.835122473329, 11216.83174752363, 11485.655430337103, 11850.466728997388, 11955.820662689892, 9050.502222377832, 9068.863967040368, 9345.158935695548, 9389.254752207074, 9443.291753118407, 8297.25324720778, 8785.297176226015, 9242.260619150586, 9424.198943089914, 9438.52807359643], "value": 6426.168365908898, "unit": "score"}], "name": "dromaeo_css", "value": 6225.266151301906, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 114.0, 116.0, 114.0, 113.0, 113.0, 113.0, 115.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 128.0, 125.0, 210.0, 124.0, 125.0, 149.0, 122.0, 188.0, 122.0], "value": 144.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 164.0, 161.0, 162.0, 162.0, 163.0, 165.0, 164.0, 160.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 100.0, 76.0, 76.0, 75.0, 75.0, 111.0, 78.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 129.0, 86.0, 85.0, 85.0, 134.0, 84.0, 87.0, 126.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 126.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 147.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 62.0, 63.0, 65.0, 64.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 89.0, 80.0, 79.0, 79.0, 79.0, 78.0, 83.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 133.0, 140.0, 138.0, 133.0, 129.0, 129.0, 133.0, 141.0], "value": 134.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 167.0, 165.0, 167.0, 163.0, 164.0, 163.0, 162.0, 168.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 78.0, 66.0, 68.0, 68.0, 67.0, 66.0, 66.0, 67.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1535.6, "alertThreshold": 2.0}]} 16:48:27 INFO - Return code: 0 16:48:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:48:27 INFO - # TBPL SUCCESS # 16:48:27 INFO - Running post-action listener: _resource_record_post_action 16:48:27 INFO - [mozharness: 2016-08-07 23:48:27.485536Z] Finished run-tests step (success) 16:48:27 INFO - Running post-run listener: _resource_record_post_run 16:48:27 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548929536; Read time: 8; Write time: 457184 16:48:27 INFO - TinderboxPrint: CPU usage
13.0% 16:48:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:48:27 INFO - TinderboxPrint: I/O write bytes / time
548,929,536 / 457,184 16:48:27 INFO - TinderboxPrint: CPU idle
8,334.9 (86.9%) 16:48:27 INFO - TinderboxPrint: CPU user
1,204.6 (12.6%) 16:48:27 INFO - TinderboxPrint: Swap in / out
0 / 0 16:48:27 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 247181312; Read time: 0; Write time: 234028 16:48:27 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 297922560; Read time: 0; Write time: 222588 16:48:28 INFO - Running post-run listener: _upload_blobber_files 16:48:28 INFO - Blob upload gear active. 16:48:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:48:28 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 16:48:28 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:48:28 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:48:28 INFO - (blobuploader) - INFO - Open directory for files ... 16:48:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:48:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:48:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:29 INFO - (blobuploader) - INFO - Done attempting. 16:48:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:48:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:48:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:30 INFO - (blobuploader) - INFO - Done attempting. 16:48:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:48:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:48:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:48:33 INFO - (blobuploader) - INFO - Done attempting. 16:48:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:48:33 INFO - Return code: 0 16:48:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:48:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:48:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e4f9509bf2997e1dec982df47c4fbdfce403816f41e66deaac3128e96303b70f0450ce5d3b5778e96a214b5568e43c1440b743b47aa588aa0c2b8fce676dc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b6b2d1df0dd45d9e90c3cdbbf3cf61004fd1256945dd07b9fa9fc593c88c7f3f0a99580689e176ca78b2b10cdb1f02681703ea71e12946ec6fd09cbd708f7ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5261ae41ae59dd645409b826671d428594d37da6474c0aefc6961549b5af0061e6d53a502582a430243d7d83166fe6a3d91ded5498b16fae8ff5b573be760707"} 16:48:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:48:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:48:33 INFO - Contents: 16:48:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7e4f9509bf2997e1dec982df47c4fbdfce403816f41e66deaac3128e96303b70f0450ce5d3b5778e96a214b5568e43c1440b743b47aa588aa0c2b8fce676dc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b6b2d1df0dd45d9e90c3cdbbf3cf61004fd1256945dd07b9fa9fc593c88c7f3f0a99580689e176ca78b2b10cdb1f02681703ea71e12946ec6fd09cbd708f7ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5261ae41ae59dd645409b826671d428594d37da6474c0aefc6961549b5af0061e6d53a502582a430243d7d83166fe6a3d91ded5498b16fae8ff5b573be760707"} 16:48:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:48:33 INFO - Copying logs to upload dir... 16:48:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:48:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.171289 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-08-07 16:48:32.341684) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 16:48:32.345566) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935 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-Non-PGO/sha512/7e4f9509bf2997e1dec982df47c4fbdfce403816f41e66deaac3128e96303b70f0450ce5d3b5778e96a214b5568e43c1440b743b47aa588aa0c2b8fce676dc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b6b2d1df0dd45d9e90c3cdbbf3cf61004fd1256945dd07b9fa9fc593c88c7f3f0a99580689e176ca78b2b10cdb1f02681703ea71e12946ec6fd09cbd708f7ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5261ae41ae59dd645409b826671d428594d37da6474c0aefc6961549b5af0061e6d53a502582a430243d7d83166fe6a3d91ded5498b16fae8ff5b573be760707"} build_url:https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/PTFyqbStT3-v9HN48tOJSw/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-Non-PGO/sha512/7e4f9509bf2997e1dec982df47c4fbdfce403816f41e66deaac3128e96303b70f0450ce5d3b5778e96a214b5568e43c1440b743b47aa588aa0c2b8fce676dc7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b6b2d1df0dd45d9e90c3cdbbf3cf61004fd1256945dd07b9fa9fc593c88c7f3f0a99580689e176ca78b2b10cdb1f02681703ea71e12946ec6fd09cbd708f7ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5261ae41ae59dd645409b826671d428594d37da6474c0aefc6961549b5af0061e6d53a502582a430243d7d83166fe6a3d91ded5498b16fae8ff5b573be760707"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 16:48:32.374898) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:48:32.375667) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1470604841.735244-1874783935 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:48:32.459351) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 16:48:32.460069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 16:48:32.460835) ========= ========= Total master_lag: 0.16 =========