builder: autoland_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-046 starttime: 1468252990.18 results: success (0) buildid: 20160711073142 builduid: e61e0c453cf04d789fb7ef46464b74b8 revision: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.184970) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.185468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.185882) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.201714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.202057) ========= 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=652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 09:03:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.9M=0s 2016-07-11 09:03:11 (50.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.348770 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.565202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.565527) ========= 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=652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017043 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.615536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:10.615978) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6b329e5cac8634acbfb8a10bcfc441f7498bab23 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 6b329e5cac8634acbfb8a10bcfc441f7498bab23 --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=652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 09:03:11,238 truncating revision to first 12 chars 2016-07-11 09:03:11,238 Setting DEBUG logging. 2016-07-11 09:03:11,238 attempt 1/10 2016-07-11 09:03:11,238 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6b329e5cac86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 09:03:11,532 unpacking tar archive at: autoland-6b329e5cac86/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662314 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:11.292846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:11.293199) ========= script_repo_revision: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:11.293585) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:11.293856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 09:03:11.302781) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-11 09:03:11.303057) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:03:12 INFO - MultiFileLogger online at 20160711 09:03:12 in /builds/slave/test 09:03:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 09:03:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:03:12 INFO - {'append_to_log': False, 09:03:12 INFO - 'base_work_dir': '/builds/slave/test', 09:03:12 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:03:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:03:12 INFO - 'branch': 'Autoland-Non-PGO', 09:03:12 INFO - 'buildbot_json_path': 'buildprops.json', 09:03:12 INFO - 'config_files': ('talos/linux_config.py',), 09:03:12 INFO - 'default_actions': ('clobber', 09:03:12 INFO - 'read-buildbot-config', 09:03:12 INFO - 'download-and-extract', 09:03:12 INFO - 'populate-webroot', 09:03:12 INFO - 'create-virtualenv', 09:03:12 INFO - 'install', 09:03:12 INFO - 'run-tests'), 09:03:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:03:12 INFO - 'download_minidump_stackwalk': True, 09:03:12 INFO - 'download_symbols': 'ondemand', 09:03:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:03:12 INFO - 'tooltool.py': '/tools/tooltool.py', 09:03:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:03:12 INFO - '/tools/misc-python/virtualenv.py')}, 09:03:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:03:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:03:12 INFO - 'installer_path': 'installer.exe', 09:03:12 INFO - 'log_level': 'info', 09:03:12 INFO - 'log_name': 'talos', 09:03:12 INFO - 'log_to_console': True, 09:03:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:03:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:03:12 INFO - 'opt_config_files': (), 09:03:12 INFO - 'pip_index': False, 09:03:12 INFO - 'sps_profile': False, 09:03:12 INFO - 'sps_profile_interval': 0, 09:03:12 INFO - 'suite': 'dromaeojs-e10s', 09:03:12 INFO - 'system_bits': '32', 09:03:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:03:12 INFO - 'title': 'talos-linux64-ix-046', 09:03:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:03:12 INFO - 'use_talos_json': True, 09:03:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:03:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:03:12 INFO - 'work_dir': 'build'} 09:03:12 INFO - ##### 09:03:12 INFO - ##### Running clobber step. 09:03:12 INFO - ##### 09:03:12 INFO - Running pre-action listener: _resource_record_pre_action 09:03:12 INFO - Running main action method: clobber 09:03:12 INFO - rmtree: /builds/slave/test/build 09:03:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:03:13 INFO - Running post-action listener: _resource_record_post_action 09:03:13 INFO - ##### 09:03:13 INFO - ##### Finished clobber step (success) 09:03:13 INFO - ##### 09:03:13 INFO - ##### 09:03:13 INFO - ##### Running read-buildbot-config step. 09:03:13 INFO - ##### 09:03:13 INFO - Running pre-action listener: _resource_record_pre_action 09:03:13 INFO - Running main action method: read_buildbot_config 09:03:13 INFO - Using buildbot properties: 09:03:13 INFO - { 09:03:13 INFO - "project": "", 09:03:13 INFO - "product": "firefox", 09:03:13 INFO - "script_repo_revision": "production", 09:03:13 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 09:03:13 INFO - "repository": "", 09:03:13 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs-e10s", 09:03:13 INFO - "buildid": "20160711073142", 09:03:13 INFO - "pgo_build": "False", 09:03:13 INFO - "basedir": "/builds/slave/test", 09:03:13 INFO - "buildnumber": 89, 09:03:13 INFO - "slavename": "talos-linux64-ix-046", 09:03:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:03:13 INFO - "platform": "ubuntu64_hw", 09:03:13 INFO - "scheduler": "tests-autoland-linux64-talos", 09:03:13 INFO - "branch": "autoland", 09:03:13 INFO - "repo_path": "integration/autoland", 09:03:13 INFO - "revision": "6b329e5cac8634acbfb8a10bcfc441f7498bab23", 09:03:13 INFO - "stage_platform": "linux64", 09:03:13 INFO - "builduid": "e61e0c453cf04d789fb7ef46464b74b8", 09:03:13 INFO - "slavebuilddir": "test" 09:03:13 INFO - } 09:03:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:03:13 INFO - Running post-action listener: _resource_record_post_action 09:03:13 INFO - ##### 09:03:13 INFO - ##### Finished read-buildbot-config step (success) 09:03:13 INFO - ##### 09:03:13 INFO - ##### 09:03:13 INFO - ##### Running download-and-extract step. 09:03:13 INFO - ##### 09:03:13 INFO - Running pre-action listener: _resource_record_pre_action 09:03:13 INFO - Running main action method: download_and_extract 09:03:13 INFO - mkdir: /builds/slave/test/build/tests 09:03:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:13 INFO - https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:03:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:03:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:03:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:03:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:03:13 INFO - Downloaded 1361 bytes. 09:03:13 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:03:13 INFO - Using the following test package requirements: 09:03:13 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:03:13 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:03:13 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:03:13 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'jsshell-linux-x86_64.zip'], 09:03:13 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:03:13 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:03:13 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:03:13 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:03:13 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:03:13 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:03:13 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:03:13 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:03:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:13 INFO - https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:03:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:03:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:03:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:03:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:03:14 INFO - Downloaded 22737940 bytes. 09:03:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:03:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:03:16 INFO - Return code: 0 09:03:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:03:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:16 INFO - https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:03:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:03:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:03:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:03:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:03:17 INFO - Downloaded 22737940 bytes. 09:03:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:03:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:03:18 INFO - Return code: 0 09:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:18 INFO - https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:03:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:03:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:03:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:03:19 INFO - Downloaded 14403333 bytes. 09:03:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:03:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:03:19 INFO - Return code: 0 09:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:19 INFO - https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:03:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:03:21 INFO - Downloaded 56649723 bytes. 09:03:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:21 INFO - mkdir: /builds/slave/test/properties 09:03:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:03:21 INFO - Writing to file /builds/slave/test/properties/build_url 09:03:21 INFO - Contents: 09:03:21 INFO - build_url:https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:03:21 INFO - Running post-action listener: _resource_record_post_action 09:03:21 INFO - Running post-action listener: set_extra_try_arguments 09:03:21 INFO - ##### 09:03:21 INFO - ##### Finished download-and-extract step (success) 09:03:21 INFO - ##### 09:03:21 INFO - ##### 09:03:21 INFO - ##### Running populate-webroot step. 09:03:21 INFO - ##### 09:03:21 INFO - Running pre-action listener: _resource_record_pre_action 09:03:21 INFO - Running main action method: populate_webroot 09:03:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:03:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:03:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:03:21 INFO - '--tppagecycles', 09:03:21 INFO - '7'], 09:03:21 INFO - 'tests': ['tsvgm']}}, 09:03:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:03:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:03:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:03:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:03:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:03:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['damp', 'tps']}, 09:03:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'tests': ['damp', 'tps']}, 09:03:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['dromaeo_dom']}, 09:03:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:03:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['basic_compositor_video']}, 09:03:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:03:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['a11yr', 09:03:21 INFO - 'ts_paint', 09:03:21 INFO - 'tpaint', 09:03:21 INFO - 'sessionrestore', 09:03:21 INFO - 'sessionrestore_no_auto_restore', 09:03:21 INFO - 'tabpaint']}, 09:03:21 INFO - 'other-e10s': {'tests': ['a11yr', 09:03:21 INFO - 'ts_paint', 09:03:21 INFO - 'tpaint', 09:03:21 INFO - 'sessionrestore', 09:03:21 INFO - 'sessionrestore_no_auto_restore', 09:03:21 INFO - 'tabpaint']}, 09:03:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:03:21 INFO - 'ts_paint', 09:03:21 INFO - 'tpaint', 09:03:21 INFO - 'sessionrestore', 09:03:21 INFO - 'sessionrestore_no_auto_restore', 09:03:21 INFO - 'tabpaint']}, 09:03:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:03:21 INFO - 'ts_paint', 09:03:21 INFO - 'tpaint', 09:03:21 INFO - 'sessionrestore', 09:03:21 INFO - 'sessionrestore_no_auto_restore', 09:03:21 INFO - 'tabpaint']}, 09:03:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['a11yr', 09:03:21 INFO - 'ts_paint', 09:03:21 INFO - 'tpaint', 09:03:21 INFO - 'sessionrestore', 09:03:21 INFO - 'sessionrestore_no_auto_restore', 09:03:21 INFO - 'tabpaint']}, 09:03:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['a11yr', 09:03:21 INFO - 'ts_paint', 09:03:21 INFO - 'tpaint', 09:03:21 INFO - 'sessionrestore', 09:03:21 INFO - 'sessionrestore_no_auto_restore', 09:03:21 INFO - 'tabpaint']}, 09:03:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['tsvgx', 09:03:21 INFO - 'tsvgr_opacity', 09:03:21 INFO - 'tart', 09:03:21 INFO - 'tscrollx', 09:03:21 INFO - 'cart']}, 09:03:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:03:21 INFO - 'tsvgr_opacity', 09:03:21 INFO - 'tart', 09:03:21 INFO - 'tscrollx', 09:03:21 INFO - 'cart']}, 09:03:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'talos_options': ['--disable-e10s'], 09:03:21 INFO - 'tests': ['tp5o']}, 09:03:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'tests': ['tp5o']}, 09:03:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'talos_options': ['--disable-e10s', 09:03:21 INFO - '--xperf_path', 09:03:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:21 INFO - 'tests': ['tp5n']}, 09:03:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:03:21 INFO - 'talos_options': ['--xperf_path', 09:03:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:03:21 INFO - 'tests': ['tp5n']}}, 09:03:21 INFO - 'talos.zip': {'path': '', 09:03:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:03:21 INFO - Running post-action listener: _resource_record_post_action 09:03:21 INFO - ##### 09:03:21 INFO - ##### Finished populate-webroot step (success) 09:03:21 INFO - ##### 09:03:21 INFO - ##### 09:03:21 INFO - ##### Running create-virtualenv step. 09:03:21 INFO - ##### 09:03:21 INFO - Running pre-action listener: _resource_record_pre_action 09:03:21 INFO - Running main action method: create_virtualenv 09:03:21 INFO - Creating virtualenv /builds/slave/test/build/venv 09:03:21 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 09:03:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:03:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:03:22 INFO - Using real prefix '/usr' 09:03:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:03:22 INFO - Installing distribute.............................................................................................................................................................................................done. 09:03:24 INFO - Installing pip.................done. 09:03:24 INFO - Return code: 0 09:03:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:24 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 09:03:24 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 09:03:24 INFO - Using env: {'DISPLAY': ':0', 09:03:24 INFO - 'HOME': '/home/cltbld', 09:03:24 INFO - 'LANG': 'en_US.UTF-8', 09:03:24 INFO - 'LANGUAGE': 'en_US:en', 09:03:24 INFO - 'LOGNAME': 'cltbld', 09:03:24 INFO - 'MAIL': '/var/mail/cltbld', 09:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:24 INFO - 'MOZ_NO_REMOTE': '1', 09:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:24 INFO - 'NO_EM_RESTART': '1', 09:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:24 INFO - 'PWD': '/builds/slave/test', 09:03:24 INFO - 'SHELL': '/bin/bash', 09:03:24 INFO - 'SHLVL': '1', 09:03:24 INFO - 'TERM': 'linux', 09:03:24 INFO - 'TMOUT': '86400', 09:03:24 INFO - 'USER': 'cltbld', 09:03:24 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:24 INFO - '_': '/tools/buildbot/bin/python'} 09:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:24 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 09:03:24 INFO - Cleaning up... 09:03:24 INFO - Return code: 0 09:03:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:24 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:24 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 09:03:24 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 09:03:24 INFO - Using env: {'DISPLAY': ':0', 09:03:24 INFO - 'HOME': '/home/cltbld', 09:03:24 INFO - 'LANG': 'en_US.UTF-8', 09:03:24 INFO - 'LANGUAGE': 'en_US:en', 09:03:24 INFO - 'LOGNAME': 'cltbld', 09:03:24 INFO - 'MAIL': '/var/mail/cltbld', 09:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:24 INFO - 'MOZ_NO_REMOTE': '1', 09:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:24 INFO - 'NO_EM_RESTART': '1', 09:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:24 INFO - 'PWD': '/builds/slave/test', 09:03:24 INFO - 'SHELL': '/bin/bash', 09:03:24 INFO - 'SHLVL': '1', 09:03:24 INFO - 'TERM': 'linux', 09:03:24 INFO - 'TMOUT': '86400', 09:03:24 INFO - 'USER': 'cltbld', 09:03:24 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:24 INFO - '_': '/tools/buildbot/bin/python'} 09:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:24 INFO - Downloading/unpacking psutil>=3.1.1 09:03:24 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 09:03:24 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 09:03:24 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 09:03:24 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 09:03:24 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 09:03:24 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 09:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:03:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:26 INFO - Installing collected packages: psutil 09:03:26 INFO - Running setup.py install for psutil 09:03:26 INFO - building 'psutil._psutil_linux' extension 09:03:26 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 09:03:27 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 09:03:27 INFO - building 'psutil._psutil_posix' extension 09:03:27 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 09:03:27 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 09:03:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:03:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:03:27 INFO - Successfully installed psutil 09:03:27 INFO - Cleaning up... 09:03:27 INFO - Return code: 0 09:03:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:03:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:27 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 09:03:27 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 09:03:27 INFO - Using env: {'DISPLAY': ':0', 09:03:27 INFO - 'HOME': '/home/cltbld', 09:03:27 INFO - 'LANG': 'en_US.UTF-8', 09:03:27 INFO - 'LANGUAGE': 'en_US:en', 09:03:27 INFO - 'LOGNAME': 'cltbld', 09:03:27 INFO - 'MAIL': '/var/mail/cltbld', 09:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:27 INFO - 'MOZ_NO_REMOTE': '1', 09:03:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:27 INFO - 'NO_EM_RESTART': '1', 09:03:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:27 INFO - 'PWD': '/builds/slave/test', 09:03:27 INFO - 'SHELL': '/bin/bash', 09:03:27 INFO - 'SHLVL': '1', 09:03:27 INFO - 'TERM': 'linux', 09:03:27 INFO - 'TMOUT': '86400', 09:03:27 INFO - 'USER': 'cltbld', 09:03:27 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:27 INFO - '_': '/tools/buildbot/bin/python'} 09:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:03:27 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 09:03:27 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 09:03:27 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 09:03:27 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 09:03:27 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 09:03:27 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 09:03:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:03:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:03:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:03:29 INFO - Installing collected packages: mozsystemmonitor 09:03:29 INFO - Running setup.py install for mozsystemmonitor 09:03:29 INFO - Successfully installed mozsystemmonitor 09:03:29 INFO - Cleaning up... 09:03:29 INFO - Return code: 0 09:03:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:03:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:29 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:29 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:29 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 09:03:29 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 09:03:29 INFO - Using env: {'DISPLAY': ':0', 09:03:29 INFO - 'HOME': '/home/cltbld', 09:03:29 INFO - 'LANG': 'en_US.UTF-8', 09:03:29 INFO - 'LANGUAGE': 'en_US:en', 09:03:29 INFO - 'LOGNAME': 'cltbld', 09:03:29 INFO - 'MAIL': '/var/mail/cltbld', 09:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:29 INFO - 'MOZ_NO_REMOTE': '1', 09:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:29 INFO - 'NO_EM_RESTART': '1', 09:03:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:29 INFO - 'PWD': '/builds/slave/test', 09:03:29 INFO - 'SHELL': '/bin/bash', 09:03:29 INFO - 'SHLVL': '1', 09:03:29 INFO - 'TERM': 'linux', 09:03:29 INFO - 'TMOUT': '86400', 09:03:29 INFO - 'USER': 'cltbld', 09:03:29 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:29 INFO - '_': '/tools/buildbot/bin/python'} 09:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:29 INFO - Downloading/unpacking jsonschema==2.5.1 09:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:29 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 09:03:29 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 09:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:03:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:03:31 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 09:03:31 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 09:03:31 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 09:03:31 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 09:03:31 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 09:03:31 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 09:03:32 INFO - Downloading functools32-3.2.3-2.tar.gz 09:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:03:32 INFO - warning: no files found matching '*.txt' 09:03:32 INFO - no previously-included directories found matching 'build' 09:03:32 INFO - no previously-included directories found matching 'dist' 09:03:32 INFO - no previously-included directories found matching '.git*' 09:03:32 INFO - Installing collected packages: jsonschema, functools32 09:03:32 INFO - Running setup.py install for functools32 09:03:32 INFO - warning: no files found matching '*.txt' 09:03:32 INFO - no previously-included directories found matching 'build' 09:03:32 INFO - no previously-included directories found matching 'dist' 09:03:32 INFO - no previously-included directories found matching '.git*' 09:03:32 INFO - Successfully installed jsonschema functools32 09:03:32 INFO - Cleaning up... 09:03:32 INFO - Return code: 0 09:03:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:03:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:32 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 09:03:32 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 09:03:32 INFO - Using env: {'DISPLAY': ':0', 09:03:32 INFO - 'HOME': '/home/cltbld', 09:03:32 INFO - 'LANG': 'en_US.UTF-8', 09:03:32 INFO - 'LANGUAGE': 'en_US:en', 09:03:32 INFO - 'LOGNAME': 'cltbld', 09:03:32 INFO - 'MAIL': '/var/mail/cltbld', 09:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:32 INFO - 'MOZ_NO_REMOTE': '1', 09:03:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:32 INFO - 'NO_EM_RESTART': '1', 09:03:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:32 INFO - 'PWD': '/builds/slave/test', 09:03:32 INFO - 'SHELL': '/bin/bash', 09:03:32 INFO - 'SHLVL': '1', 09:03:32 INFO - 'TERM': 'linux', 09:03:32 INFO - 'TMOUT': '86400', 09:03:32 INFO - 'USER': 'cltbld', 09:03:32 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:32 INFO - '_': '/tools/buildbot/bin/python'} 09:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:03:32 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 09:03:32 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 09:03:32 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 09:03:32 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 09:03:32 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 09:03:32 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 09:03:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:03:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:03:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 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 09:03:34 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 09:03:35 INFO - Downloading docopt-0.6.1.tar.gz 09:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:03:35 INFO - Installing collected packages: blobuploader, requests, docopt 09:03:35 INFO - Running setup.py install for blobuploader 09:03:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:03:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:03:35 INFO - Running setup.py install for requests 09:03:35 INFO - Running setup.py install for docopt 09:03:35 INFO - Successfully installed blobuploader requests docopt 09:03:35 INFO - Cleaning up... 09:03:35 INFO - Return code: 0 09:03:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:35 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:35 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 09:03:35 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 09:03:35 INFO - Using env: {'DISPLAY': ':0', 09:03:35 INFO - 'HOME': '/home/cltbld', 09:03:35 INFO - 'LANG': 'en_US.UTF-8', 09:03:35 INFO - 'LANGUAGE': 'en_US:en', 09:03:35 INFO - 'LOGNAME': 'cltbld', 09:03:35 INFO - 'MAIL': '/var/mail/cltbld', 09:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:35 INFO - 'MOZ_NO_REMOTE': '1', 09:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:35 INFO - 'NO_EM_RESTART': '1', 09:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:35 INFO - 'PWD': '/builds/slave/test', 09:03:35 INFO - 'SHELL': '/bin/bash', 09:03:35 INFO - 'SHLVL': '1', 09:03:35 INFO - 'TERM': 'linux', 09:03:35 INFO - 'TMOUT': '86400', 09:03:35 INFO - 'USER': 'cltbld', 09:03:35 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:35 INFO - '_': '/tools/buildbot/bin/python'} 09:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:36 INFO - Running setup.py (path:/tmp/pip-oE7vKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:36 INFO - Running setup.py (path:/tmp/pip-B_M3Z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:36 INFO - Running setup.py (path:/tmp/pip-WVic1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:36 INFO - Running setup.py (path:/tmp/pip-FdezfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:36 INFO - Running setup.py (path:/tmp/pip-AdE_Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:36 INFO - Running setup.py (path:/tmp/pip-vIQhAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:36 INFO - Running setup.py (path:/tmp/pip-1CaA_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:36 INFO - Running setup.py (path:/tmp/pip-S1uQR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:36 INFO - Running setup.py (path:/tmp/pip-MEPXPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:36 INFO - Running setup.py (path:/tmp/pip-YYySBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:36 INFO - Running setup.py (path:/tmp/pip-q_MsAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:36 INFO - Running setup.py (path:/tmp/pip-yQl1Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:36 INFO - Running setup.py (path:/tmp/pip-OyHBAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:37 INFO - Running setup.py (path:/tmp/pip-BxnYPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:37 INFO - Running setup.py (path:/tmp/pip-ArJSw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:37 INFO - Running setup.py (path:/tmp/pip-iyZQqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:37 INFO - Running setup.py (path:/tmp/pip-OUoz0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:37 INFO - Running setup.py install for manifestparser 09:03:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Running setup.py install for mozcrash 09:03:37 INFO - Running setup.py install for mozdebug 09:03:37 INFO - Running setup.py install for mozdevice 09:03:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Running setup.py install for mozfile 09:03:37 INFO - Running setup.py install for mozhttpd 09:03:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:37 INFO - Running setup.py install for mozinfo 09:03:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozInstall 09:03:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozleak 09:03:38 INFO - Running setup.py install for mozlog 09:03:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for moznetwork 09:03:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozprocess 09:03:38 INFO - Running setup.py install for mozprofile 09:03:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozrunner 09:03:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:38 INFO - Running setup.py install for mozscreenshot 09:03:38 INFO - Running setup.py install for moztest 09:03:38 INFO - Running setup.py install for mozversion 09:03:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:39 INFO - Cleaning up... 09:03:39 INFO - Return code: 0 09:03:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:39 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:39 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 09:03:39 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 09:03:39 INFO - Using env: {'DISPLAY': ':0', 09:03:39 INFO - 'HOME': '/home/cltbld', 09:03:39 INFO - 'LANG': 'en_US.UTF-8', 09:03:39 INFO - 'LANGUAGE': 'en_US:en', 09:03:39 INFO - 'LOGNAME': 'cltbld', 09:03:39 INFO - 'MAIL': '/var/mail/cltbld', 09:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:39 INFO - 'MOZ_NO_REMOTE': '1', 09:03:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:39 INFO - 'NO_EM_RESTART': '1', 09:03:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:39 INFO - 'PWD': '/builds/slave/test', 09:03:39 INFO - 'SHELL': '/bin/bash', 09:03:39 INFO - 'SHLVL': '1', 09:03:39 INFO - 'TERM': 'linux', 09:03:39 INFO - 'TMOUT': '86400', 09:03:39 INFO - 'USER': 'cltbld', 09:03:39 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:39 INFO - '_': '/tools/buildbot/bin/python'} 09:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:39 INFO - Running setup.py (path:/tmp/pip-QAvLuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:39 INFO - Running setup.py (path:/tmp/pip-TiSkBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:39 INFO - Running setup.py (path:/tmp/pip-ULBWZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:39 INFO - Running setup.py (path:/tmp/pip-BOURbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:39 INFO - Running setup.py (path:/tmp/pip-j77fnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:39 INFO - Running setup.py (path:/tmp/pip-dZsbg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:39 INFO - Running setup.py (path:/tmp/pip-4HmIpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:39 INFO - Running setup.py (path:/tmp/pip-DwMeXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:39 INFO - Running setup.py (path:/tmp/pip-5omORZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:39 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)) 09:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:39 INFO - Running setup.py (path:/tmp/pip-fx_bq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:40 INFO - Running setup.py (path:/tmp/pip-is12DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:40 INFO - Running setup.py (path:/tmp/pip-sobYNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:40 INFO - Running setup.py (path:/tmp/pip-K86fJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:40 INFO - Running setup.py (path:/tmp/pip-VrKhIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:40 INFO - Running setup.py (path:/tmp/pip-eCmQpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:40 INFO - Running setup.py (path:/tmp/pip-YLpY9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:40 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)) 09:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:40 INFO - Running setup.py (path:/tmp/pip-E9IpWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:40 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)) 09:03:40 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)) 09:03:40 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)) 09:03:40 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)) 09:03:40 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)) 09:03:40 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)) 09:03:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:42 INFO - Downloading blessings-1.6.tar.gz 09:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:42 INFO - Installing collected packages: blessings 09:03:42 INFO - Running setup.py install for blessings 09:03:42 INFO - Successfully installed blessings 09:03:42 INFO - Cleaning up... 09:03:42 INFO - Return code: 0 09:03:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:42 INFO - Reading from file tmpfile_stdout 09:03:42 INFO - Current package versions: 09:03:42 INFO - argparse == 1.2.1 09:03:42 INFO - blessings == 1.6 09:03:42 INFO - blobuploader == 1.2.4 09:03:42 INFO - docopt == 0.6.1 09:03:42 INFO - functools32 == 3.2.3-2 09:03:42 INFO - jsonschema == 2.5.1 09:03:42 INFO - manifestparser == 1.1 09:03:42 INFO - mozInstall == 1.12 09:03:42 INFO - mozcrash == 0.17 09:03:42 INFO - mozdebug == 0.1 09:03:42 INFO - mozdevice == 0.48 09:03:42 INFO - mozfile == 1.2 09:03:42 INFO - mozhttpd == 0.7 09:03:42 INFO - mozinfo == 0.9 09:03:42 INFO - mozleak == 0.1 09:03:42 INFO - mozlog == 3.2 09:03:42 INFO - moznetwork == 0.27 09:03:42 INFO - mozprocess == 0.23 09:03:42 INFO - mozprofile == 0.28 09:03:42 INFO - mozrunner == 6.12 09:03:42 INFO - mozscreenshot == 0.1 09:03:42 INFO - mozsystemmonitor == 0.3 09:03:42 INFO - moztest == 0.7 09:03:42 INFO - mozversion == 1.4 09:03:42 INFO - psutil == 3.1.1 09:03:42 INFO - requests == 1.2.3 09:03:42 INFO - wsgiref == 0.1.2 09:03:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:42 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:42 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 09:03:42 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 09:03:42 INFO - Using env: {'DISPLAY': ':0', 09:03:42 INFO - 'HOME': '/home/cltbld', 09:03:42 INFO - 'LANG': 'en_US.UTF-8', 09:03:42 INFO - 'LANGUAGE': 'en_US:en', 09:03:42 INFO - 'LOGNAME': 'cltbld', 09:03:42 INFO - 'MAIL': '/var/mail/cltbld', 09:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:42 INFO - 'MOZ_NO_REMOTE': '1', 09:03:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:42 INFO - 'NO_EM_RESTART': '1', 09:03:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:42 INFO - 'PWD': '/builds/slave/test', 09:03:42 INFO - 'SHELL': '/bin/bash', 09:03:42 INFO - 'SHLVL': '1', 09:03:42 INFO - 'TERM': 'linux', 09:03:42 INFO - 'TMOUT': '86400', 09:03:42 INFO - 'USER': 'cltbld', 09:03:42 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:42 INFO - '_': '/tools/buildbot/bin/python'} 09:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 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)) 09:03:43 INFO - Cleaning up... 09:03:43 INFO - Return code: 0 09:03:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:03:43 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 0x1c56030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1db98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e85400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, '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': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', '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 09:03:43 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 09:03:43 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 09:03:43 INFO - Using env: {'DISPLAY': ':0', 09:03:43 INFO - 'HOME': '/home/cltbld', 09:03:43 INFO - 'LANG': 'en_US.UTF-8', 09:03:43 INFO - 'LANGUAGE': 'en_US:en', 09:03:43 INFO - 'LOGNAME': 'cltbld', 09:03:43 INFO - 'MAIL': '/var/mail/cltbld', 09:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:43 INFO - 'MOZ_NO_REMOTE': '1', 09:03:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:43 INFO - 'NO_EM_RESTART': '1', 09:03:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:43 INFO - 'PWD': '/builds/slave/test', 09:03:43 INFO - 'SHELL': '/bin/bash', 09:03:43 INFO - 'SHLVL': '1', 09:03:43 INFO - 'TERM': 'linux', 09:03:43 INFO - 'TMOUT': '86400', 09:03:43 INFO - 'USER': 'cltbld', 09:03:43 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:43 INFO - '_': '/tools/buildbot/bin/python'} 09:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:43 INFO - Cleaning up... 09:03:43 INFO - Return code: 0 09:03:43 INFO - Running post-action listener: _resource_record_post_action 09:03:43 INFO - Running post-action listener: _start_resource_monitoring 09:03:43 INFO - Starting resource monitoring. 09:03:43 INFO - ##### 09:03:43 INFO - ##### Finished create-virtualenv step (success) 09:03:43 INFO - ##### 09:03:43 INFO - ##### 09:03:43 INFO - ##### Running install step. 09:03:43 INFO - ##### 09:03:43 INFO - Running pre-action listener: _resource_record_pre_action 09:03:43 INFO - Running main action method: install 09:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:43 INFO - Reading from file tmpfile_stdout 09:03:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:43 INFO - Reading from file tmpfile_stdout 09:03:43 INFO - Output received: 09:03:43 INFO - Usage: mozinstall [options] installer 09:03:43 INFO - Options: 09:03:43 INFO - -h, --help show this help message and exit 09:03:43 INFO - -d DEST, --destination=DEST 09:03:43 INFO - Directory to install application into. [default: 09:03:43 INFO - "/builds/slave/test"] 09:03:43 INFO - --app=APP Application being installed. [default: firefox] 09:03:43 INFO - mkdir: /builds/slave/test/build/application 09:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:03:59 INFO - Reading from file tmpfile_stdout 09:03:59 INFO - Output received: 09:03:59 INFO - /builds/slave/test/build/application/firefox/firefox 09:03:59 INFO - Running post-action listener: _resource_record_post_action 09:03:59 INFO - ##### 09:03:59 INFO - ##### Finished install step (success) 09:03:59 INFO - ##### 09:03:59 INFO - ##### 09:03:59 INFO - ##### Running run-tests step. 09:03:59 INFO - ##### 09:03:59 INFO - Running pre-action listener: _resource_record_pre_action 09:03:59 INFO - Running main action method: run_tests 09:03:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:59 INFO - Python 2.7.3 09:03:59 INFO - Return code: 0 09:03:59 INFO - grabbing minidump binary from tooltool 09:03: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:59 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 0x1f14570>, '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 0x1f15470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f15900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:59 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 09:03:59 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 09:03:59 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 09:04:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:04:00 INFO - Return code: 0 09:04:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:04:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:04:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:04:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:04:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:04:00 INFO - Using env: {'DISPLAY': ':0', 09:04:00 INFO - 'HOME': '/home/cltbld', 09:04:00 INFO - 'LANG': 'en_US.UTF-8', 09:04:00 INFO - 'LANGUAGE': 'en_US:en', 09:04:00 INFO - 'LOGNAME': 'cltbld', 09:04:00 INFO - 'MAIL': '/var/mail/cltbld', 09:04:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:04:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:04:00 INFO - 'MOZ_NO_REMOTE': '1', 09:04:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:04:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:04:00 INFO - 'NO_EM_RESTART': '1', 09:04:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:04:00 INFO - 'PWD': '/builds/slave/test', 09:04:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:04:00 INFO - 'SHELL': '/bin/bash', 09:04:00 INFO - 'SHLVL': '1', 09:04:00 INFO - 'TERM': 'linux', 09:04:00 INFO - 'TMOUT': '86400', 09:04:00 INFO - 'USER': 'cltbld', 09:04:00 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568', 09:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:04:00 INFO - '_': '/tools/buildbot/bin/python'} 09:04:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:04:00 INFO - mozversion application_buildid: 20160711073142 09:04:00 INFO - mozversion application_changeset: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 09:04:00 INFO - mozversion application_display_name: Nightly 09:04:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:04:00 INFO - mozversion application_name: Firefox 09:04:00 INFO - mozversion application_remotingname: firefox 09:04:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:04:00 INFO - mozversion application_vendor: Mozilla 09:04:00 INFO - mozversion application_version: 50.0a1 09:04:00 INFO - mozversion platform_buildid: 20160711073142 09:04:00 INFO - mozversion platform_changeset: 6b329e5cac8634acbfb8a10bcfc441f7498bab23 09:04:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:04:00 INFO - mozversion platform_version: 50.0a1 09:04:00 INFO - using testdate: 1468253040 09:04:00 INFO - actual date: 1468253040 09:04:00 INFO - starting webserver on 'localhost:39887' 09:04:00 INFO - SUITE-START | Running 2 tests 09:04:00 INFO - TEST-START | dromaeo_css 09:04:00 INFO - operating with platform_type : linux_ 09:04:00 INFO - Initialising browser for dromaeo_css test... 09:04:00 INFO - TEST-INFO | started process 17800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkwvVIp/profile http://localhost:39887/getInfo.html) 09:04:05 INFO - PROCESS | 17800 | __metrics Screen width/height:1600/1200 09:04:05 INFO - PROCESS | 17800 | colorDepth:24 09:04:05 INFO - PROCESS | 17800 | Browser inner width/height: 1024/697 09:04:05 INFO - PROCESS | 17800 | __metrics 09:04:06 ERROR - PROCESS | 17800 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 09:04:07 INFO - TEST-INFO | 17800: exit 0 09:04:07 INFO - Browser initialized. 09:04:07 INFO - Running cycle 1/1 for dromaeo_css test... 09:04:07 INFO - TEST-INFO | started process 17918 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkwvVIp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:19 INFO - PROCESS | 17918 | RSS: Main: 168775680 09:04:19 INFO - PROCESS | 17918 | 09:05:08 INFO - PROCESS | 17918 | 1468253108685 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] 09:07:21 INFO - PROCESS | 17918 | Cycle 1(1): loaded http://localhost:39887/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39887/tests/dromaeo/cssquery-ext.html) 09:07:22 INFO - PROCESS | 17918 | RSS: Main: 168402944 09:07:22 INFO - PROCESS | 17918 | 09:10:24 INFO - PROCESS | 17918 | Cycle 1(1): loaded http://localhost:39887/tests/dromaeo/cssquery-ext.html (next: http://localhost:39887/tests/dromaeo/cssquery-jquery.html) 09:10:24 INFO - PROCESS | 17918 | RSS: Main: 168439808 09:10:24 INFO - PROCESS | 17918 | 09:13:27 INFO - PROCESS | 17918 | Cycle 1(1): loaded http://localhost:39887/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39887/tests/dromaeo/cssquery-mootools.html) 09:13:27 INFO - PROCESS | 17918 | RSS: Main: 171372544 09:13:27 INFO - PROCESS | 17918 | 09:16:29 INFO - PROCESS | 17918 | Cycle 1(1): loaded http://localhost:39887/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39887/tests/dromaeo/cssquery-prototype.html) 09:16:30 INFO - PROCESS | 17918 | RSS: Main: 167145472 09:16:30 INFO - PROCESS | 17918 | 09:19:33 INFO - PROCESS | 17918 | Cycle 1(1): loaded http://localhost:39887/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39887/tests/dromaeo/cssquery-yui.html) 09:19:33 INFO - PROCESS | 17918 | RSS: Main: 168955904 09:19:33 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | Cycle 1(1): loaded http://localhost:39887/tests/dromaeo/cssquery-yui.html (next: http://localhost:39887/tests/dromaeo/cssquery-dojo.html) 09:22:36 INFO - PROCESS | 17918 | RSS: Main: 170283008 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | __start_tp_report 09:22:36 INFO - PROCESS | 17918 | _x_x_mozilla_page_load 09:22:36 INFO - PROCESS | 17918 | _x_x_mozilla_page_load_details 09:22:36 INFO - PROCESS | 17918 | |i|pagename|runs| 09:22:36 INFO - PROCESS | 17918 | |0;dojo.html;2628.500584888871;2649.89400423983;2657.840529568226;2680.6917204521487;2689.085710858309;4725.598324142447;4755.476897541268;4848.587870031047;4864;4876.0247950409885;4666.206744853374;4790.856274311767;4795.400574928134;4803.183458812002;4819.397575303087;4372.825086996524;4421.513633500313;4496.752678602676;4498.347739577759;4506.324051392285;195.55712906770518;4284.742915425085;4303.935440968372;4316.352547117926;4392.494863090754;4342.32693932441;4352.651787856979;4357.586029327209;4372.540883207249;4421.889452763675;4355.259605867011;4379.58393952574;4387.758673272969;4404.427424434835;4407.647388208936;4258.957410425887;4328.567143285678;4344.652427805768;4352.064306174156;4355.912881742379;4699.201135806921;4709.152352576535;4709.6938698984795;4724.055188962187;4736.976315118402;4990.6257030722845;5111.207762796773;5125.692458452468;5148.613853960968;5162.483751624807;5076.974615126937;5086.821961231376;5087.87280317995;5097.057044446752;5164.04465173944;4761.928571071437;4792.760361981887;4795.736234507066;4804.975975120172;4852.902941941176;5259.894802103937;5265.815696450606;5317.335333083364;5318.414974352818;5333.57331413486;5087.821926232564;5095.821646242438;5097.1844504879045;5101.438841727407;5111.514406131411;5134.563562097189;5138;5150.871228219325;5187.870303242374;5199.532042116228;4983.078130545789;5009.599232061426;5084.3644544431945;5086.0845047891;5097.209932460474;5027.346444962204;5030.943501864576;5041.571466425322;5043.646944713908;5061.620378471629;4227.8097485613225;4293.119910418357;4313.41768861206;4350.477942646902;4395.934060989088;3690.2250527389547;3715.9071023224637;3733.701303895682;3746.213295208037;3750.249950010009;4200.180964711913;4237.470316210473;4309.762963037063;4339.674524410681;4382.956170438319;5171.896562068813;5174.508421699932;5183.403908550489;5188.558972487381;5221.921671174935;4267.039916018871;4337.392765012901;4349.412829268072;4375.518692943773;4414.757188354672;4552.544745525487;4594.95405045952;4598.2412901871485;4601.079784043205;4606.815727370935;4746.1931471649905;4755.310479980314;4773.498782627774;4787.042591481787;4822.638302127354;4998.575121114601;5009.123403404463;5113.386393632788;5125.513076257749;5183.377994640517;4697.859064228079;4737.620990320835;4748.596369308648;4753.096911586787;4814.1815891297865;4960.9829984852295;4970.080535100873;4970.751462426936;4980.850574482772;5015.548600625962;4981.900361992813;4995.975020124876;5023.246513023076;5034.59723222134;5068.619853511075;4912.582430493448;4915.311856340044;4955.603551715926;4964.900701986012;4982.402111746614;4875.1712208923855;4913.582345500529;4915.557599816034;4954.77703503336;4966.776495057658;4626.32918226862;4657.045305712389;4742.66801323904;4794.137055330074;4891.608671306358;4907.975460122677;4936.2102063669645;4976.825811096594;4979.775910084128;5008.4240312364545;4828.855134345975;4832.226843704991;4839.008003359232;4883.926741098815;4891.192953162688 09:22:36 INFO - PROCESS | 17918 | |1;ext.html;554.5258803722817;554.7531348549894;567.860874085849;574.0642752313728;594.4085634793381;35384.82307588462;38610.227795444145;38632.42051369225;38639.420408693855;38677.80661096694;38540.229195416076;39177.608223917756;39308.21383572327;39329;39352.40971385424;7678.731244406447;7889.934858794047;7894.80262993424;7895.526268423884;7901.565413902233;7729.72945946892;7801.375889928792;7826.80432989177;7836.373090152802;7887.487313324616;7848.921510784876;7872.803179920519;7899.4075444341615;7919.049714034324;7919.326857217144;7848.529088254694;7877.606119693993;7891.6054197289905;7897.763067107996;7902.170272121431;7828.295453409163;7892.80267993299;7900.486468379594;7903.051633803922;7903.36773058154;37217.46434088757;37432.43851342232;37664.43503347452;37701.43447848284;37755.056123596856;2975.8809647614285;3115.626124865008;3179.856906439216;3206.887758928426;3225.2098235932112;29417.55873661897;29581.852090739623;29728.702712973027;30010;30105.397892042256;2903.637045369329;3081.8613162407746;3086.5215891536845;3112.859921303547;3117;214266;269912;271844;272129.6393518039;272580;53073;53520.46479535232;53611.19583206255;53636.731816341446;53656.99999999922;33247;34296.14259643479;34376.14059648529;35597.46603800945;35658.82170589131;13108.344582770824;13180.275084870424;13191.934040329737;13233.272170030741;13370.130941488773;9789.804203915883;9905.405675659484;9913.058259465339;9967.55146018431;10007.799844003224;17688.734668979974;17729.379471718687;17745;17812.862456505303;17930.462086137435;6688.665566721645;6790.966045169743;6801.65991700413;6801.65991700413;6933.2026816915695;731.3417923868454;786.811165320319;895.0825403960862;984.247051005981;990.6681261777242;26096.739032609432;26966.5955010675;27687.584686229722;28163.859180703967;28394.43211135807;911.7036962986923;917.9082091790901;948.3409030723581;958.678842587741;1041.510490069666;13662.590122296348;13765.724685506233;13809.447622095204;13950.372233249529;14179.645508862155;404692;404938;406989;409274;409660;48930.26604600934;48955.26567101496;49191;49206;49494.50505494971;1169.906407487399;1173.5540494611994;1181.7163880668577;1182.9171957963033;1212.0969877441187;24302.87848560746;25026.37434064163;26106.60840087362;26739.598906016814;26936.865315673294;40954.79522602368;41302.586974129874;41409;41411.378829318186;41511.37732933948;324724;325348;327622;327647;328038;25105.121320753686;25293.494130116927;25908;25990.87004565041;26060;42109;42113;42183;42596.1480770389;42990.35514467223;36658.8167059163;36966;37144.628553714116;37496.437553436175;37786.811065944494;36151.27697446089;36791.08991566826;37682;38160.618393815705;40129;35490.64509354874;36353;36473.635263647026;36901.81549092345;37070;31408.214794630316;32692;32955;33646;33793.83103084469;24828.255152345457;25204.243872683117;25422.491550169263;25575.488490230462;25750.227493175236 09:22:36 INFO - PROCESS | 17918 | |2;jquery.html;363.58551251573203;365.4135113143405;374.7564083345825;383.7719298245613;389.3167491053201;16480.340786368546;17291.913540432295;17922.910385448053;17993.460196194133;18048.09759512024;16208.675826483463;17104.82895171048;17161.313547458118;17214.741778873326;17577.472675819747;4734.502877197896;4798.904021919577;4846.273059041137;4864.343313652647;4899.48555401683;3523.3481805865995;4783.665143439961;5265.131253343213;5568.693721845297;5568.9443105569035;5579.163125531162;5585.776568937237;5618;5633.183188437674;5644.181593668915;5699.715014249292;6176.660283684396;6186.7525298987985;6204.81385558434;6215.036669316263;4556.635469162459;5304.602154838364;5387.973060134714;5388.91916621251;5423.810166644188;12239.877601223963;12561.874381256162;12798.16811907223;12834.16577922432;12841.35793210345;1780.5370603643016;1830.3136323878546;1960.0591715976411;2206.1285792112185;2363.976360236393;9006.324525660537;9085.772855678595;9316.767080822965;9403.57683904226;9625.51872406377;1640.0569672438396;1705.599184191714;1726.47342560519;1731.9740203896952;1854.8330650241408;72830.63584682095;75113;75361.24638753597;75777;75964.24035759571;10141.340812847142;10371.27401081917;10406.895931040593;10513.001264879824;10641.627543035956;14062;14158.787618185735;14170.645733856736;14207.218602976729;14226.928865355814;6843.213030501456;7131.429485641136;7195.640217989079;7222.458315626349;7258.165310989304;6552.967235163794;6595.70319335631;6684;6732.629705366154;6738.393544581011;4346.565343465628;4372.300431930939;4401.361802538614;4407.228734971367;4492.730436173774;1671.4484220207303;1744.2412550540555;1787.3386846866742;1876.6300600561667;1904.7714274287175;792.6987744656993;822.6750433578823;871.6513394642194;914.6707185413246;931.9673811416568;15871.365145394286;16504.00975941447;16825.32698692063;16895.746563801553;17129.22918468672;849.4308813095241;856.4176360075085;887.316766090107;895;901.3104974694364;6750.5274630775375;8937.597808098652;9357.953210233905;9379.624815007459;9857.852132218022;376877;384566;386531;390206;390216;80521.59739201383;81588;82529.17470825335;82547.17452825395;82673;903.051795614602;905.8822353094056;914.2183433164681;921.1847514949184;924.8936372317135;14703.3091194514;14742.11547307165;14952.925235373754;15052.774208387102;15225.695486090437;36407.635923640424;37088.774673518594;37672.43491347577;37953;38351;48116.5188348126;48898.08815294839;52918.735406322725;56555.71722141363;57215.14177287261;6778.7288508459105;7505.3620442260835;7704.345130663733;7721.420893432903;7860.764177074697;10079.69760907174;12325.50697972071;12403.131780775258;12680;12793.808092878427;10434.58261669555;10641.095506882002;10659;10684.09185219234;11013.614523491638;9000.954995224982;9238.538073096184;9806.950965245129;9948.910728747043;10139.087482126644;9813.11681948628;10145.442000689738;10202.234832387452;10249.692509224735;10345.327553708985;7130.037444944866;7203.2076471589135;7320.133365991638;7324.63376831167;7425.554466732014;8101.473404228706;10140.594376224868;10141.543630536791;10283.794324113625;10309.123724483203 09:22:36 INFO - PROCESS | 17918 | |3;mootools.html;1376.8967327450441;1405.0796728143066;1483.940642374305;1675.405231142945;1703.880728349016;6534.085228068075;6902.723891044358;7038.120234970629;7379.1145062592495;7379.520331178476;7291.343779059884;7439.070116235471;7509.624518774053;7551.24487551246;7571.280728330799;2142.068200332856;2166.458385403649;2182.378022263655;2189.7481789594162;2195.2316689158824;2129.882856442895;2144.592527419793;2219.178903805594;2224.132588290568;2227.042371780134;2168.4687251623373;2172.0660116150048;2181.0621432783896;2188.7154669892893;2216.3461778775218;2120.088362004345;2141.475338542067;2144.5925274197853;2153.3001774423296;2159.2334721173916;2081.396395045435;2122.373899699592;2133.232036466871;2133.4133113393787;2138.786121387864;7394.2975417335265;7467.066616672916;7470.252974702486;7486.401087913008;7495.175530691619;4309.1381723655395;4388.078503514266;4415.227335216357;4418.911621767579;4429.557044295577;2773.833569985807;2781.652293463317;2820.294926268433;2852.343960889007;2890.56641503774;822.6627082895984;834.4284165346696;883.0771843423573;903.5843511984496;904.3759805734088;9830.311878168532;9853.21174306061;9871.210303175729;9887.950560247153;9900.60397584103;3921.686265098785;4025.355943049098;4155.168966206771;4163;4169.60388763067;8279.420440569224;8290.544020078954;8303.086660467343;8308.169183081643;8311.8337633247;4537.659675524349;4547.567981041796;4564.3838081859185;4567;4568.1548913451115;3686.576043754949;4038.414429907647;4192.7065105442325;4198.517170525368;4203.684723645678;3520.0847779577493;3538.4869193967247;3577.94633080504;3616.258666973264;3631.1103779573646;1130.4404319861721;1158.6639874436487;1158.8435561199299;1173.501261963679;1182.2138278045177;719.7804669575829;761.1551178192202;787.5117427195061;797.1231645190244;813.8657121574873;4611.100835337077;4723.504032076583;4748.074125545486;4806.759662016955;4881;410.8130800485772;419.645399637306;419.90971941032825;421.2396624093541;429.0603578163811;3066.5296224965305;3107.192130046204;3165.778395512292;3203.439398105322;3231.7091461768555;8734.65061397537;9063.909360906438;9213.401128926598;9223.44659320443;9236.353455258068;8831.249343805848;8944.642214311485;9077.183053525083;9184.219341355925;9212.078792120734;7065.258147894499;7089.645517724093;7114.537555059008;7117.288271172842;7170.175429825533;4533.116042371706;4600.7469589172915;4668.809453589313;4681.110588988081;4755.358026666494;8366.958165209135;8628.78428039304;8632.395732298679;8658.220760131366;8690.304775618062;8668.0898505656;8683.261922736638;8755.824883502422;8779.561021949004;8794.252488538546;5607.523360514238;6126.326104128453;6376.171097757262;6525.2169739631545;6558.409743123142;7181.102362204724;7182.461315401261;7204.639768011473;7229.240929702306;7321.450891183076;6233.127362169391;6439.420452159328;6444.581102228341;6681;6794.796156115324;6180.6600636965395;6476.4495017924;6492.64290464029;6626.138601981712;6665.966670166618;5624.072028115479;5744.0522313817755;5764.510016648632;5783.045797443375;5798.043322851851;4060.2488539620263;4169.645580125723;4205.243056249782;4206.369044643328;4351.282038463744;4329.848455304049;4362.890927726832;4376.956230437655;4445.510993790615;4472.530384309601 09:22:36 INFO - PROCESS | 17918 | |4;prototype.html;283.2451516707973;293.3531562903797;303.1844338728819;309.6021612228287;311.3120004789415;5030.496950304968;5049.596032317415;5070.771815268313;5082.822101226458;5099.36257967754;4993.651714037217;4997.350344455225;5000.974800165966;5021.464160598902;5023.221400682891;3753.1555400035045;3844.1735026969195;3939.3500072488005;3958.386450100239;3976.6619837313865;3944.9211015779665;3946.48695669564;3958.6342711764537;3960.267350540145;3964.484616999786;3921.6274453926976;3924.725269231155;3957.9010524736823;3961.584033676466;3968.6428221460064;3783.4892289540835;3912.6283003114945;3945.8618948336957;3948.3287841066945;3956.9010774730573;3473.94789078164;3485.3552092862647;3487.3897068013;3488.337215928965;3490.5462289902403;4813.951860481386;4824.421069471651;4881.267150161712;4891.413030436335;4903.754812259408;4418.513963464016;4475;4504.346869703907;4516.57092576208;4530.79611417487;5221.817236396708;5225.111731005738;5260.210968354739;5260.684358938438;5270.5520030797425;3798.7274263121885;3799.9430008549894;3802.505674262356;3823.560290566593;3840.0207946973624;5392.91910621345;5512.310961129859;5546.500814926676;5556.749946252388;5570.972145139248;3911.6870650347905;3914.236723838881;3937.5078115235597;3944.526656801202;3950.9802450987563;3984.4820173377275;3991.481107456012;3994.5206575210577;4007.519097708294;4014.6788256939376;4494.258447356149;4514.6614003949835;4519.751413672279;4524.25349817283;4534.954650453519;5155.922661160085;5225.346831646044;5311.7344132793205;5312.415634280226;5318.308619879468;1361.9387127579282;1370.3216907630785;1381.716748066644;1387.7293927684207;1388.5004195468869;858.6436628799104;879.7404765594165;891.2825175733467;898.5237823953315;910.4628269321;4407.647388208936;4541.023679908835;4544.318352247124;4566.49768525462;4568.469562696534;5376.1398176291605;5377.27406800077;5413.188021796762;5415.783368665209;5425.26758887553;4343.67422443318;4553.840615578439;4586.495485496593;4618.745968971672;4618.930716039262;2855.4289142171647;2855.928601784972;2864.3268831824503;2873.5976963225166;2880.5967164596973;5020.723860187724;5030.446650868402;5033.546980771748;5041.403677106293;5056.82301119459;5304.549113325334;5536.2249285100115;5540.418256083134;5564.4713752193475;5648.689322087324;5027.396712394463;5088.847334579968;5114.48855114493;5125.769340379692;5154.14956532168;5371.785128594813;5454.154606036071;5454.427285135003;5457.536109430743;5466.34403871538;5263.499902527761;5327.387350454747;5338.423256418131;5371.758270877742;5419.485148910847;5046.066477701637;5053.393592768891;5083.491650834887;5083.694978301313;5087.771050302671;3940.842366305316;3983.820728067186;3989.800509974548;3993.5607083221403;4002.9192118128526;5201.661891977009;5360.16917377807;5360.892782144413;5372.086745253238;5398.271233383443;5166.796586116632;5198.922016169836;5220.269162317354;5245.6852588844795;5252.474752524717;5220;5230.503102205284;5255.343082114736;5255.78976840922;5278.86802829917;4916.541729135432;5086;5090.618203634668;5093.337866077386;5134.794608215779;5093.745312734274;5106.4893510648635;5280.788768449219;5301.840944771663;5311.787528498817;5126.846194614168;5127.79488820443;5144.202648589475;5154.175331946871;5165.586753059822 09:22:36 INFO - PROCESS | 17918 | |5;yui.html;666.5667316244441;679.1578442731012;683.3337495941457;693.313619516678;744.6276861569215;10976.231663783528;11467.483963221654;11516.13628977827;11516.30902145872;11523.942380288086;11296.491657875395;11332.093432525398;11337.546498140064;11346.546138154465;11349.716257093576;629.0721186250267;767.0833354141789;773.3928865840317;782.9451938364317;793.8094857234252;789.4986683456018;792.7146227358176;792.7978365516814;792.9365650747956;795.952242865427;828.3000864269683;830.3606223208126;831.6922738586701;833.2708879730236;835.106436113359;814.1858141858141;819.1112642782529;819.3608984991716;820.0733171516208;820.7578764264556;604.3050491934267;605.0651743056943;605.4308949587373;605.7637521366671;607.9969600152015;11702.53189872404;11768.176227664067;11787.528498860036;11801.114916381306;11828.881711182865;13541.796873046913;13625.454981800814;13684.65788355289;14184.929075354661;14202.289885505787;10232.334898231591;10253.179745620333;10383.532741026673;10974.561017559288;11102.833457498144;4337.674674399433;4384;4387.758673272969;4408.272635015219;4502.5047244803045;62285;62755.37244627541;62770;63668.363316366704;64377;10803.081738052353;11006.724831879266;11101.333919964827;11169.77742289656;11257.774844503228;21094.156233750786;21328.14687412486;21859.344219673436;21946.341609751733;21994.34016979493;6515.566575353415;6538.574992625464;6610.107635469246;6619.900701489481;6760.695768690421;3589.192431702846;3644.635536446387;3746.606606306353;3765.4163604641326;3853.6339047790416;2895.290653789793;2980.940381192364;2985.686502917206;2998.1005698290423;3004.1288026472516;502.99497005030213;506.30888836738325;511.79272394680214;512.8794733237686;524.1325606121875;523.4137765702437;579.1139556478595;606.4996377988177;606.9302030266576;618.5051958433236;8895.73312800617;9097.09029097085;9163.541822908961;9264.629414823467;9333.953330233442;613.285522366447;615.6860001399318;619.9752009919553;629.1129507394552;650.3983814971133;3981.5023122109737;4033.4554835097474;4097.262492751273;4225.514065882401;4329.935050974238;54384.728076359366;97996.53005204927;100219.49890250502;106740.46629766801;110542.44728776305;94796.52601737085;95589;95966;97433.5384969226;98098;83391.58304208562;84349.57825210835;85548.57225713956;85808.99999999875;86452.13547864566;8471.407001509835;8969.14793094655;9242;9273.211776999031;9882.901170988485;29633.703662963093;42494;42573.57426425696;43165.352519712855;43266.783666081465;62985;64187;64367.67816161077;64671;65220;11513.942430287794;11572.36352000648;11804.82292765592;11904.523819047488;11950.760984780081;13083.476660933457;13303.135296205715;13445.865541344852;13541.05212635106;13733;12056.51773929033;12613.74772504563;12662.240265584094;13335.66660833487;13479.797803032767;11524.94237528807;11711.355875426932;11811.291322520676;12209.877901220874;12248.755024899274;12055;12165.330906799856;12561.120721549403;12593.496260149492;12966.157199781985;9722.562484688064;9852.950735246279;10228.897711022795;10437.478126093816;10475.161987040867;9688.224942004763;9998.500074996076;10450.16398688088;10501.527431265764;10746.462676866282 09:22:36 INFO - PROCESS | 17918 | __end_tp_report 09:22:36 INFO - PROCESS | 17918 | __start_cc_report 09:22:36 INFO - PROCESS | 17918 | _x_x_mozilla_cycle_collect,388 09:22:36 INFO - PROCESS | 17918 | __end_cc_report 09:22:36 INFO - PROCESS | 17918 | __startTimestamp1468254156697__endTimestamp 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | ------- Summary: start ------- 09:22:36 INFO - PROCESS | 17918 | Number of tests: 6 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | [#0] dojo.html Cycles:165 Average:4672.19 Median:4814.18 stddev:611.56 (12.7%) stddev-sans-first:592.04 09:22:36 INFO - PROCESS | 17918 | Values: 2628.5 2649.9 2657.8 2680.7 2689.1 4725.6 4755.5 4848.6 4864.0 4876.0 4666.2 4790.9 4795.4 4803.2 4819.4 4372.8 4421.5 4496.8 4498.3 4506.3 195.6 4284.7 4303.9 4316.4 4392.5 4342.3 4352.7 4357.6 4372.5 4421.9 4355.3 4379.6 4387.8 4404.4 4407.6 4259.0 4328.6 4344.7 4352.1 4355.9 4699.2 4709.2 4709.7 4724.1 4737.0 4990.6 5111.2 5125.7 5148.6 5162.5 5077.0 5086.8 5087.9 5097.1 5164.0 4761.9 4792.8 4795.7 4805.0 4852.9 5259.9 5265.8 5317.3 5318.4 5333.6 5087.8 5095.8 5097.2 5101.4 5111.5 5134.6 5138.0 5150.9 5187.9 5199.5 4983.1 5009.6 5084.4 5086.1 5097.2 5027.3 5030.9 5041.6 5043.6 5061.6 4227.8 4293.1 4313.4 4350.5 4395.9 3690.2 3715.9 3733.7 3746.2 3750.2 4200.2 4237.5 4309.8 4339.7 4383.0 5171.9 5174.5 5183.4 5188.6 5221.9 4267.0 4337.4 4349.4 4375.5 4414.8 4552.5 4595.0 4598.2 4601.1 4606.8 4746.2 4755.3 4773.5 4787.0 4822.6 4998.6 5009.1 5113.4 5125.5 5183.4 4697.9 4737.6 4748.6 4753.1 4814.2 4961.0 4970.1 4970.8 4980.9 5015.5 4981.9 4996.0 5023.2 5034.6 5068.6 4912.6 4915.3 4955.6 4964.9 4982.4 4875.2 4913.6 4915.6 4954.8 4966.8 4626.3 4657.0 4742.7 4794.1 4891.6 4908.0 4936.2 4976.8 4979.8 5008.4 4828.9 4832.2 4839.0 4883.9 4891.2 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | [#1] ext.html Cycles:180 Average:47743.42 Median:37580.44 stddev:88906.31 (236.6%) stddev-sans-first:89085.12 09:22:36 INFO - PROCESS | 17918 | Values: 554.5 554.8 567.9 574.1 594.4 35384.8 38610.2 38632.4 38639.4 38677.8 38540.2 39177.6 39308.2 39329.0 39352.4 7678.7 7889.9 7894.8 7895.5 7901.6 7729.7 7801.4 7826.8 7836.4 7887.5 7848.9 7872.8 7899.4 7919.0 7919.3 7848.5 7877.6 7891.6 7897.8 7902.2 7828.3 7892.8 7900.5 7903.1 7903.4 37217.5 37432.4 37664.4 37701.4 37755.1 2975.9 3115.6 3179.9 3206.9 3225.2 29417.6 29581.9 29728.7 30010.0 30105.4 2903.6 3081.9 3086.5 3112.9 3117.0 214266.0 269912.0 271844.0 272129.6 272580.0 53073.0 53520.5 53611.2 53636.7 53657.0 33247.0 34296.1 34376.1 35597.5 35658.8 13108.3 13180.3 13191.9 13233.3 13370.1 9789.8 9905.4 9913.1 9967.6 10007.8 17688.7 17729.4 17745.0 17812.9 17930.5 6688.7 6791.0 6801.7 6801.7 6933.2 731.3 786.8 895.1 984.2 990.7 26096.7 26966.6 27687.6 28163.9 28394.4 911.7 917.9 948.3 958.7 1041.5 13662.6 13765.7 13809.4 13950.4 14179.6 404692.0 404938.0 406989.0 409274.0 409660.0 48930.3 48955.3 49191.0 49206.0 49494.5 1169.9 1173.6 1181.7 1182.9 1212.1 24302.9 25026.4 26106.6 26739.6 26936.9 40954.8 41302.6 41409.0 41411.4 41511.4 324724.0 325348.0 327622.0 327647.0 328038.0 25105.1 25293.5 25908.0 25990.9 26060.0 42109.0 42113.0 42183.0 42596.1 42990.4 36658.8 36966.0 37144.6 37496.4 37786.8 36151.3 36791.1 37682.0 38160.6 40129.0 35490.6 36353.0 36473.6 36901.8 37070.0 31408.2 32692.0 32955.0 33646.0 33793.8 24828.3 25204.2 25422.5 25575.5 25750.2 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | [#2] jquery.html Cycles:180 Average:24306.80 Median:4645.57 stddev:64018.68 (1378.1%) stddev-sans-first:64173.03 09:22:36 INFO - PROCESS | 17918 | Values: 363.6 365.4 374.8 383.8 389.3 16480.3 17291.9 17922.9 17993.5 18048.1 16208.7 17104.8 17161.3 17214.7 17577.5 4734.5 4798.9 4846.3 4864.3 4899.5 3523.3 4783.7 5265.1 5568.7 5568.9 5579.2 5585.8 5618.0 5633.2 5644.2 5699.7 6176.7 6186.8 6204.8 6215.0 4556.6 5304.6 5388.0 5388.9 5423.8 12239.9 12561.9 12798.2 12834.2 12841.4 1780.5 1830.3 1960.1 2206.1 2364.0 9006.3 9085.8 9316.8 9403.6 9625.5 1640.1 1705.6 1726.5 1732.0 1854.8 72830.6 75113.0 75361.2 75777.0 75964.2 10141.3 10371.3 10406.9 10513.0 10641.6 14062.0 14158.8 14170.6 14207.2 14226.9 6843.2 7131.4 7195.6 7222.5 7258.2 6553.0 6595.7 6684.0 6732.6 6738.4 4346.6 4372.3 4401.4 4407.2 4492.7 1671.4 1744.2 1787.3 1876.6 1904.8 792.7 822.7 871.7 914.7 932.0 15871.4 16504.0 16825.3 16895.7 17129.2 849.4 856.4 887.3 895.0 901.3 6750.5 8937.6 9358.0 9379.6 9857.9 376877.0 384566.0 386531.0 390206.0 390216.0 80521.6 81588.0 82529.2 82547.2 82673.0 903.1 905.9 914.2 921.2 924.9 14703.3 14742.1 14952.9 15052.8 15225.7 36407.6 37088.8 37672.4 37953.0 38351.0 48116.5 48898.1 52918.7 56555.7 57215.1 6778.7 7505.4 7704.3 7721.4 7860.8 10079.7 12325.5 12403.1 12680.0 12793.8 10434.6 10641.1 10659.0 10684.1 11013.6 9001.0 9238.5 9807.0 9948.9 10139.1 9813.1 10145.4 10202.2 10249.7 10345.3 7130.0 7203.2 7320.1 7324.6 7425.6 8101.5 10140.6 10141.5 10283.8 10309.1 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | [#3] mootools.html Cycles:180 Average:4865.90 Median:4735.79 stddev:2712.49 (57.3%) stddev-sans-first:2707.43 09:22:36 INFO - PROCESS | 17918 | Values: 1376.9 1405.1 1483.9 1675.4 1703.9 6534.1 6902.7 7038.1 7379.1 7379.5 7291.3 7439.1 7509.6 7551.2 7571.3 2142.1 2166.5 2182.4 2189.7 2195.2 2129.9 2144.6 2219.2 2224.1 2227.0 2168.5 2172.1 2181.1 2188.7 2216.3 2120.1 2141.5 2144.6 2153.3 2159.2 2081.4 2122.4 2133.2 2133.4 2138.8 7394.3 7467.1 7470.3 7486.4 7495.2 4309.1 4388.1 4415.2 4418.9 4429.6 2773.8 2781.7 2820.3 2852.3 2890.6 822.7 834.4 883.1 903.6 904.4 9830.3 9853.2 9871.2 9888.0 9900.6 3921.7 4025.4 4155.2 4163.0 4169.6 8279.4 8290.5 8303.1 8308.2 8311.8 4537.7 4547.6 4564.4 4567.0 4568.2 3686.6 4038.4 4192.7 4198.5 4203.7 3520.1 3538.5 3577.9 3616.3 3631.1 1130.4 1158.7 1158.8 1173.5 1182.2 719.8 761.2 787.5 797.1 813.9 4611.1 4723.5 4748.1 4806.8 4881.0 410.8 419.6 419.9 421.2 429.1 3066.5 3107.2 3165.8 3203.4 3231.7 8734.7 9063.9 9213.4 9223.4 9236.4 8831.2 8944.6 9077.2 9184.2 9212.1 7065.3 7089.6 7114.5 7117.3 7170.2 4533.1 4600.7 4668.8 4681.1 4755.4 8367.0 8628.8 8632.4 8658.2 8690.3 8668.1 8683.3 8755.8 8779.6 8794.3 5607.5 6126.3 6376.2 6525.2 6558.4 7181.1 7182.5 7204.6 7229.2 7321.5 6233.1 6439.4 6444.6 6681.0 6794.8 6180.7 6476.4 6492.6 6626.1 6666.0 5624.1 5744.1 5764.5 5783.0 5798.0 4060.2 4169.6 4205.2 4206.4 4351.3 4329.8 4362.9 4377.0 4445.5 4472.5 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | [#4] prototype.html Cycles:180 Average:4375.98 Median:5022.34 stddev:1257.78 (25.0%) stddev-sans-first:1223.22 09:22:36 INFO - PROCESS | 17918 | Values: 283.2 293.4 303.2 309.6 311.3 5030.5 5049.6 5070.8 5082.8 5099.4 4993.7 4997.4 5001.0 5021.5 5023.2 3753.2 3844.2 3939.4 3958.4 3976.7 3944.9 3946.5 3958.6 3960.3 3964.5 3921.6 3924.7 3957.9 3961.6 3968.6 3783.5 3912.6 3945.9 3948.3 3956.9 3473.9 3485.4 3487.4 3488.3 3490.5 4814.0 4824.4 4881.3 4891.4 4903.8 4418.5 4475.0 4504.3 4516.6 4530.8 5221.8 5225.1 5260.2 5260.7 5270.6 3798.7 3799.9 3802.5 3823.6 3840.0 5392.9 5512.3 5546.5 5556.7 5571.0 3911.7 3914.2 3937.5 3944.5 3951.0 3984.5 3991.5 3994.5 4007.5 4014.7 4494.3 4514.7 4519.8 4524.3 4535.0 5155.9 5225.3 5311.7 5312.4 5318.3 1361.9 1370.3 1381.7 1387.7 1388.5 858.6 879.7 891.3 898.5 910.5 4407.6 4541.0 4544.3 4566.5 4568.5 5376.1 5377.3 5413.2 5415.8 5425.3 4343.7 4553.8 4586.5 4618.7 4618.9 2855.4 2855.9 2864.3 2873.6 2880.6 5020.7 5030.4 5033.5 5041.4 5056.8 5304.5 5536.2 5540.4 5564.5 5648.7 5027.4 5088.8 5114.5 5125.8 5154.1 5371.8 5454.2 5454.4 5457.5 5466.3 5263.5 5327.4 5338.4 5371.8 5419.5 5046.1 5053.4 5083.5 5083.7 5087.8 3940.8 3983.8 3989.8 3993.6 4002.9 5201.7 5360.2 5360.9 5372.1 5398.3 5166.8 5198.9 5220.3 5245.7 5252.5 5220.0 5230.5 5255.3 5255.8 5278.9 4916.5 5086.0 5090.6 5093.3 5134.8 5093.7 5106.5 5280.8 5301.8 5311.8 5126.8 5127.8 5144.2 5154.2 5165.6 09:22:36 INFO - PROCESS | 17918 | 09:22:36 INFO - PROCESS | 17918 | [#5] yui.html Cycles:180 Average:18443.63 Median:4385.88 stddev:26876.53 (612.8%) stddev-sans-first:26918.78 09:22:36 INFO - PROCESS | 17918 | Values: 666.6 679.2 683.3 693.3 744.6 10976.2 11467.5 11516.1 11516.3 11523.9 11296.5 11332.1 11337.5 11346.5 11349.7 629.1 767.1 773.4 782.9 793.8 789.5 792.7 792.8 792.9 796.0 828.3 830.4 831.7 833.3 835.1 814.2 819.1 819.4 820.1 820.8 604.3 605.1 605.4 605.8 608.0 11702.5 11768.2 11787.5 11801.1 11828.9 13541.8 13625.5 13684.7 14184.9 14202.3 10232.3 10253.2 10383.5 10974.6 11102.8 4337.7 4384.0 4387.8 4408.3 4502.5 62285.0 62755.4 62770.0 63668.4 64377.0 10803.1 11006.7 11101.3 11169.8 11257.8 21094.2 21328.1 21859.3 21946.3 21994.3 6515.6 6538.6 6610.1 6619.9 6760.7 3589.2 3644.6 3746.6 3765.4 3853.6 2895.3 2980.9 2985.7 2998.1 3004.1 503.0 506.3 511.8 512.9 524.1 523.4 579.1 606.5 606.9 618.5 8895.7 9097.1 9163.5 9264.6 9334.0 613.3 615.7 620.0 629.1 650.4 3981.5 4033.5 4097.3 4225.5 4329.9 54384.7 97996.5 100219.5 106740.5 110542.4 94796.5 95589.0 95966.0 97433.5 98098.0 83391.6 84349.6 85548.6 85809.0 86452.1 8471.4 8969.1 9242.0 9273.2 9882.9 29633.7 42494.0 42573.6 43165.4 43266.8 62985.0 64187.0 64367.7 64671.0 65220.0 11513.9 11572.4 11804.8 11904.5 11950.8 13083.5 13303.1 13445.9 13541.1 13733.0 12056.5 12613.7 12662.2 13335.7 13479.8 11524.9 11711.4 11811.3 12209.9 12248.8 12055.0 12165.3 12561.1 12593.5 12966.2 9722.6 9853.0 10228.9 10437.5 10475.2 9688.2 9998.5 10450.2 10501.5 10746.5 09:22:36 INFO - PROCESS | 17918 | -------- Summary: end -------- 09:22:36 INFO - PROCESS | 17918 | 09:22:37 INFO - TEST-INFO | 17918: exit 0 09:22:37 INFO - TEST-OK | dromaeo_css | took 1117275ms 09:22:37 INFO - TEST-START | kraken 09:22:37 INFO - operating with platform_type : linux_ 09:22:37 INFO - Initialising browser for kraken test... 09:22:37 INFO - TEST-INFO | started process 18170 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRszcTh/profile http://localhost:39887/getInfo.html) 09:22:42 INFO - PROCESS | 18170 | __metrics Screen width/height:1600/1200 09:22:42 INFO - PROCESS | 18170 | colorDepth:24 09:22:42 INFO - PROCESS | 18170 | Browser inner width/height: 1024/697 09:22:42 INFO - PROCESS | 18170 | __metrics 09:22:43 ERROR - PROCESS | 18170 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 09:22:43 INFO - TEST-INFO | 18170: exit 0 09:22:43 INFO - Browser initialized. 09:22:43 INFO - Running cycle 1/1 for kraken test... 09:22:43 INFO - TEST-INFO | started process 18292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRszcTh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:55 INFO - PROCESS | 18292 | RSS: Main: 168980480 09:22:55 INFO - PROCESS | 18292 | 09:22:58 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39887/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:59 INFO - PROCESS | 18292 | RSS: Main: 173809664 09:22:59 INFO - PROCESS | 18292 | 09:23:02 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39887/tests/kraken/driver.html?testName=audio-dft) 09:23:02 INFO - PROCESS | 18292 | RSS: Main: 173985792 09:23:02 INFO - PROCESS | 18292 | 09:23:05 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39887/tests/kraken/driver.html?testName=audio-fft) 09:23:06 INFO - PROCESS | 18292 | RSS: Main: 168632320 09:23:06 INFO - PROCESS | 18292 | 09:23:08 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39887/tests/kraken/driver.html?testName=audio-oscillator) 09:23:09 INFO - PROCESS | 18292 | RSS: Main: 168886272 09:23:09 INFO - PROCESS | 18292 | 09:23:11 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39887/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:23:12 INFO - PROCESS | 18292 | RSS: Main: 169177088 09:23:12 INFO - PROCESS | 18292 | 09:23:15 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39887/tests/kraken/driver.html?testName=imaging-darkroom) 09:23:15 INFO - PROCESS | 18292 | RSS: Main: 169914368 09:23:15 INFO - PROCESS | 18292 | 09:23:19 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39887/tests/kraken/driver.html?testName=imaging-desaturate) 09:23:20 INFO - PROCESS | 18292 | RSS: Main: 167022592 09:23:20 INFO - PROCESS | 18292 | 09:23:23 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39887/tests/kraken/driver.html?testName=json-parse-financial) 09:23:23 INFO - PROCESS | 18292 | RSS: Main: 168304640 09:23:23 INFO - PROCESS | 18292 | 09:23:25 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39887/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:23:26 INFO - PROCESS | 18292 | RSS: Main: 168919040 09:23:26 INFO - PROCESS | 18292 | 09:23:28 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:23:28 INFO - PROCESS | 18292 | RSS: Main: 169336832 09:23:28 INFO - PROCESS | 18292 | 09:23:30 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:23:31 INFO - PROCESS | 18292 | RSS: Main: 169811968 09:23:31 INFO - PROCESS | 18292 | 09:23:34 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:23:35 INFO - PROCESS | 18292 | RSS: Main: 169709568 09:23:35 INFO - PROCESS | 18292 | 09:23:37 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:38 INFO - PROCESS | 18292 | RSS: Main: 169320448 09:23:38 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | Cycle 1(1): loaded http://localhost:39887/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39887/tests/kraken/driver.html?testName=ai-astar) 09:23:40 INFO - PROCESS | 18292 | RSS: Main: 170254336 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | __start_tp_report 09:23:40 INFO - PROCESS | 18292 | _x_x_mozilla_page_load 09:23:40 INFO - PROCESS | 18292 | _x_x_mozilla_page_load_details 09:23:40 INFO - PROCESS | 18292 | |i|pagename|runs| 09:23:40 INFO - PROCESS | 18292 | |0;ai-astar;107;115;109;116;117;117;120;114;116;115 09:23:40 INFO - PROCESS | 18292 | |1;audio-beat-detection;153;122;146;177;129;152;151;125;196;124 09:23:40 INFO - PROCESS | 18292 | |2;audio-dft;162;164;163;169;176;163;164;163;165;163 09:23:40 INFO - PROCESS | 18292 | |3;audio-fft;75;77;77;162;77;77;76;78;162;76 09:23:40 INFO - PROCESS | 18292 | |4;audio-oscillator;85;86;150;87;86;88;135;86;86;128 09:23:40 INFO - PROCESS | 18292 | |5;imaging-gaussian-blur;119;118;118;117;118;117;118;118;119;118 09:23:40 INFO - PROCESS | 18292 | |6;imaging-darkroom;149;149;147;149;148;149;147;148;147;149 09:23:40 INFO - PROCESS | 18292 | |7;imaging-desaturate;97;96;95;95;95;95;96;96;96;95 09:23:40 INFO - PROCESS | 18292 | |8;json-parse-financial;64;62;63;63;65;64;65;65;64;64 09:23:40 INFO - PROCESS | 18292 | |9;json-stringify-tinderbox;65;65;65;65;66;65;65;65;65;65 09:23:40 INFO - PROCESS | 18292 | |10;stanford-crypto-aes;85;86;91;82;82;79;84;86;85;84 09:23:40 INFO - PROCESS | 18292 | |11;stanford-crypto-ccm;138;142;135;138;142;139;136;142;169;139 09:23:40 INFO - PROCESS | 18292 | |12;stanford-crypto-pbkdf2;167;173;167;175;174;171;174;171;169;169 09:23:40 INFO - PROCESS | 18292 | |13;stanford-crypto-sha256-iterative;68;70;70;68;70;66;67;68;68;67 09:23:40 INFO - PROCESS | 18292 | __end_tp_report 09:23:40 INFO - PROCESS | 18292 | __start_cc_report 09:23:40 INFO - PROCESS | 18292 | _x_x_mozilla_cycle_collect,976 09:23:40 INFO - PROCESS | 18292 | __end_cc_report 09:23:40 INFO - PROCESS | 18292 | __startTimestamp1468254220620__endTimestamp 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | ------- Summary: start ------- 09:23:40 INFO - PROCESS | 18292 | Number of tests: 14 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#0] ai-astar Cycles:10 Average:114.60 Median:116.00 stddev:3.86 (3.3%) stddev-sans-first:2.96 09:23:40 INFO - PROCESS | 18292 | Values: 107.0 115.0 109.0 116.0 117.0 117.0 120.0 114.0 116.0 115.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#1] audio-beat-detection Cycles:10 Average:147.50 Median:151.50 stddev:24.36 (16.1%) stddev-sans-first:25.75 09:23:40 INFO - PROCESS | 18292 | Values: 153.0 122.0 146.0 177.0 129.0 152.0 151.0 125.0 196.0 124.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#2] audio-dft Cycles:10 Average:165.20 Median:164.00 stddev:4.26 (2.6%) stddev-sans-first:4.36 09:23:40 INFO - PROCESS | 18292 | Values: 162.0 164.0 163.0 169.0 176.0 163.0 164.0 163.0 165.0 163.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#3] audio-fft Cycles:10 Average:93.70 Median:77.00 stddev:36.01 (46.8%) stddev-sans-first:37.55 09:23:40 INFO - PROCESS | 18292 | Values: 75.0 77.0 77.0 162.0 77.0 77.0 76.0 78.0 162.0 76.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:86.00 stddev:25.39 (29.5%) stddev-sans-first:26.20 09:23:40 INFO - PROCESS | 18292 | Values: 85.0 86.0 150.0 87.0 86.0 88.0 135.0 86.0 86.0 128.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 09:23:40 INFO - PROCESS | 18292 | Values: 119.0 118.0 118.0 117.0 118.0 117.0 118.0 118.0 119.0 118.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:149.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 09:23:40 INFO - PROCESS | 18292 | Values: 149.0 149.0 147.0 149.0 148.0 149.0 147.0 148.0 147.0 149.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 09:23:40 INFO - PROCESS | 18292 | Values: 97.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 95.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 09:23:40 INFO - PROCESS | 18292 | Values: 64.0 62.0 63.0 63.0 65.0 64.0 65.0 65.0 64.0 64.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 09:23:40 INFO - PROCESS | 18292 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 65.0 65.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:3.17 (3.7%) stddev-sans-first:3.35 09:23:40 INFO - PROCESS | 18292 | Values: 85.0 86.0 91.0 82.0 82.0 79.0 84.0 86.0 85.0 84.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:140.50 stddev:9.80 (7.0%) stddev-sans-first:10.28 09:23:40 INFO - PROCESS | 18292 | Values: 138.0 142.0 135.0 138.0 142.0 139.0 136.0 142.0 169.0 139.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:172.00 stddev:2.94 (1.7%) stddev-sans-first:2.74 09:23:40 INFO - PROCESS | 18292 | Values: 167.0 173.0 167.0 175.0 174.0 171.0 174.0 171.0 169.0 169.0 09:23:40 INFO - PROCESS | 18292 | 09:23:40 INFO - PROCESS | 18292 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.40 (2.1%) stddev-sans-first:1.48 09:23:40 INFO - PROCESS | 18292 | Values: 68.0 70.0 70.0 68.0 70.0 66.0 67.0 68.0 68.0 67.0 09:23:40 INFO - PROCESS | 18292 | -------- Summary: end -------- 09:23:40 INFO - PROCESS | 18292 | 09:23:41 INFO - TEST-INFO | 18292: exit 0 09:23:41 INFO - TEST-OK | kraken | took 64165ms 09:23:41 INFO - SUITE-END | took 1181s 09:23:41 INFO - Completed test suite (00:19:41) 09:23:41 INFO - javascript benchmark 09:23:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6374.986965665242, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2628.500584888871, 2649.89400423983, 2657.840529568226, 2680.6917204521487, 2689.085710858309, 4725.598324142447, 4755.476897541268, 4848.587870031047, 4864.0, 4876.0247950409885, 4666.206744853374, 4790.856274311767, 4795.400574928134, 4803.183458812002, 4819.397575303087, 4372.825086996524, 4421.513633500313, 4496.752678602676, 4498.347739577759, 4506.324051392285, 195.55712906770518, 4284.742915425085, 4303.935440968372, 4316.352547117926, 4392.494863090754, 4342.32693932441, 4352.651787856979, 4357.586029327209, 4372.540883207249, 4421.889452763675, 4355.259605867011, 4379.58393952574, 4387.758673272969, 4404.427424434835, 4407.647388208936, 4258.957410425887, 4328.567143285678, 4344.652427805768, 4352.064306174156, 4355.912881742379, 4699.201135806921, 4709.152352576535, 4709.6938698984795, 4724.055188962187, 4736.976315118402, 4990.6257030722845, 5111.207762796773, 5125.692458452468, 5148.613853960968, 5162.483751624807, 5076.974615126937, 5086.821961231376, 5087.87280317995, 5097.057044446752, 5164.04465173944, 4761.928571071437, 4792.760361981887, 4795.736234507066, 4804.975975120172, 4852.902941941176, 5259.894802103937, 5265.815696450606, 5317.335333083364, 5318.414974352818, 5333.57331413486, 5087.821926232564, 5095.821646242438, 5097.1844504879045, 5101.438841727407, 5111.514406131411, 5134.563562097189, 5138.0, 5150.871228219325, 5187.870303242374, 5199.532042116228, 4983.078130545789, 5009.599232061426, 5084.3644544431945, 5086.0845047891, 5097.209932460474, 5027.346444962204, 5030.943501864576, 5041.571466425322, 5043.646944713908, 5061.620378471629, 4227.8097485613225, 4293.119910418357, 4313.41768861206, 4350.477942646902, 4395.934060989088, 3690.2250527389547, 3715.9071023224637, 3733.701303895682, 3746.213295208037, 3750.249950010009, 4200.180964711913, 4237.470316210473, 4309.762963037063, 4339.674524410681, 4382.956170438319, 5171.896562068813, 5174.508421699932, 5183.403908550489, 5188.558972487381, 5221.921671174935, 4267.039916018871, 4337.392765012901, 4349.412829268072, 4375.518692943773, 4414.757188354672, 4552.544745525487, 4594.95405045952, 4598.2412901871485, 4601.079784043205, 4606.815727370935, 4746.1931471649905, 4755.310479980314, 4773.498782627774, 4787.042591481787, 4822.638302127354, 4998.575121114601, 5009.123403404463, 5113.386393632788, 5125.513076257749, 5183.377994640517, 4697.859064228079, 4737.620990320835, 4748.596369308648, 4753.096911586787, 4814.1815891297865, 4960.9829984852295, 4970.080535100873, 4970.751462426936, 4980.850574482772, 5015.548600625962, 4981.900361992813, 4995.975020124876, 5023.246513023076, 5034.59723222134, 5068.619853511075, 4912.582430493448, 4915.311856340044, 4955.603551715926, 4964.900701986012, 4982.402111746614, 4875.1712208923855, 4913.582345500529, 4915.557599816034, 4954.77703503336, 4966.776495057658, 4626.32918226862, 4657.045305712389, 4742.66801323904, 4794.137055330074, 4891.608671306358, 4907.975460122677, 4936.2102063669645, 4976.825811096594, 4979.775910084128, 5008.4240312364545, 4828.855134345975, 4832.226843704991, 4839.008003359232, 4883.926741098815, 4891.192953162688], "value": 4634.9206267600175, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.5258803722817, 554.7531348549894, 567.860874085849, 574.0642752313728, 594.4085634793381, 35384.82307588462, 38610.227795444145, 38632.42051369225, 38639.420408693855, 38677.80661096694, 38540.229195416076, 39177.608223917756, 39308.21383572327, 39329.0, 39352.40971385424, 7678.731244406447, 7889.934858794047, 7894.80262993424, 7895.526268423884, 7901.565413902233, 7729.72945946892, 7801.375889928792, 7826.80432989177, 7836.373090152802, 7887.487313324616, 7848.921510784876, 7872.803179920519, 7899.4075444341615, 7919.049714034324, 7919.326857217144, 7848.529088254694, 7877.606119693993, 7891.6054197289905, 7897.763067107996, 7902.170272121431, 7828.295453409163, 7892.80267993299, 7900.486468379594, 7903.051633803922, 7903.36773058154, 37217.46434088757, 37432.43851342232, 37664.43503347452, 37701.43447848284, 37755.056123596856, 2975.8809647614285, 3115.626124865008, 3179.856906439216, 3206.887758928426, 3225.2098235932112, 29417.55873661897, 29581.852090739623, 29728.702712973027, 30010.0, 30105.397892042256, 2903.637045369329, 3081.8613162407746, 3086.5215891536845, 3112.859921303547, 3117.0, 214266.0, 269912.0, 271844.0, 272129.6393518039, 272580.0, 53073.0, 53520.46479535232, 53611.19583206255, 53636.731816341446, 53656.99999999922, 33247.0, 34296.14259643479, 34376.14059648529, 35597.46603800945, 35658.82170589131, 13108.344582770824, 13180.275084870424, 13191.934040329737, 13233.272170030741, 13370.130941488773, 9789.804203915883, 9905.405675659484, 9913.058259465339, 9967.55146018431, 10007.799844003224, 17688.734668979974, 17729.379471718687, 17745.0, 17812.862456505303, 17930.462086137435, 6688.665566721645, 6790.966045169743, 6801.65991700413, 6801.65991700413, 6933.2026816915695, 731.3417923868454, 786.811165320319, 895.0825403960862, 984.247051005981, 990.6681261777242, 26096.739032609432, 26966.5955010675, 27687.584686229722, 28163.859180703967, 28394.43211135807, 911.7036962986923, 917.9082091790901, 948.3409030723581, 958.678842587741, 1041.510490069666, 13662.590122296348, 13765.724685506233, 13809.447622095204, 13950.372233249529, 14179.645508862155, 404692.0, 404938.0, 406989.0, 409274.0, 409660.0, 48930.26604600934, 48955.26567101496, 49191.0, 49206.0, 49494.50505494971, 1169.906407487399, 1173.5540494611994, 1181.7163880668577, 1182.9171957963033, 1212.0969877441187, 24302.87848560746, 25026.37434064163, 26106.60840087362, 26739.598906016814, 26936.865315673294, 40954.79522602368, 41302.586974129874, 41409.0, 41411.378829318186, 41511.37732933948, 324724.0, 325348.0, 327622.0, 327647.0, 328038.0, 25105.121320753686, 25293.494130116927, 25908.0, 25990.87004565041, 26060.0, 42109.0, 42113.0, 42183.0, 42596.1480770389, 42990.35514467223, 36658.8167059163, 36966.0, 37144.628553714116, 37496.437553436175, 37786.811065944494, 36151.27697446089, 36791.08991566826, 37682.0, 38160.618393815705, 40129.0, 35490.64509354874, 36353.0, 36473.635263647026, 36901.81549092345, 37070.0, 31408.214794630316, 32692.0, 32955.0, 33646.0, 33793.83103084469, 24828.255152345457, 25204.243872683117, 25422.491550169263, 25575.488490230462, 25750.227493175236], "value": 17126.506473615933, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.58551251573203, 365.4135113143405, 374.7564083345825, 383.7719298245613, 389.3167491053201, 16480.340786368546, 17291.913540432295, 17922.910385448053, 17993.460196194133, 18048.09759512024, 16208.675826483463, 17104.82895171048, 17161.313547458118, 17214.741778873326, 17577.472675819747, 4734.502877197896, 4798.904021919577, 4846.273059041137, 4864.343313652647, 4899.48555401683, 3523.3481805865995, 4783.665143439961, 5265.131253343213, 5568.693721845297, 5568.9443105569035, 5579.163125531162, 5585.776568937237, 5618.0, 5633.183188437674, 5644.181593668915, 5699.715014249292, 6176.660283684396, 6186.7525298987985, 6204.81385558434, 6215.036669316263, 4556.635469162459, 5304.602154838364, 5387.973060134714, 5388.91916621251, 5423.810166644188, 12239.877601223963, 12561.874381256162, 12798.16811907223, 12834.16577922432, 12841.35793210345, 1780.5370603643016, 1830.3136323878546, 1960.0591715976411, 2206.1285792112185, 2363.976360236393, 9006.324525660537, 9085.772855678595, 9316.767080822965, 9403.57683904226, 9625.51872406377, 1640.0569672438396, 1705.599184191714, 1726.47342560519, 1731.9740203896952, 1854.8330650241408, 72830.63584682095, 75113.0, 75361.24638753597, 75777.0, 75964.24035759571, 10141.340812847142, 10371.27401081917, 10406.895931040593, 10513.001264879824, 10641.627543035956, 14062.0, 14158.787618185735, 14170.645733856736, 14207.218602976729, 14226.928865355814, 6843.213030501456, 7131.429485641136, 7195.640217989079, 7222.458315626349, 7258.165310989304, 6552.967235163794, 6595.70319335631, 6684.0, 6732.629705366154, 6738.393544581011, 4346.565343465628, 4372.300431930939, 4401.361802538614, 4407.228734971367, 4492.730436173774, 1671.4484220207303, 1744.2412550540555, 1787.3386846866742, 1876.6300600561667, 1904.7714274287175, 792.6987744656993, 822.6750433578823, 871.6513394642194, 914.6707185413246, 931.9673811416568, 15871.365145394286, 16504.00975941447, 16825.32698692063, 16895.746563801553, 17129.22918468672, 849.4308813095241, 856.4176360075085, 887.316766090107, 895.0, 901.3104974694364, 6750.5274630775375, 8937.597808098652, 9357.953210233905, 9379.624815007459, 9857.852132218022, 376877.0, 384566.0, 386531.0, 390206.0, 390216.0, 80521.59739201383, 81588.0, 82529.17470825335, 82547.17452825395, 82673.0, 903.051795614602, 905.8822353094056, 914.2183433164681, 921.1847514949184, 924.8936372317135, 14703.3091194514, 14742.11547307165, 14952.925235373754, 15052.774208387102, 15225.695486090437, 36407.635923640424, 37088.774673518594, 37672.43491347577, 37953.0, 38351.0, 48116.5188348126, 48898.08815294839, 52918.735406322725, 56555.71722141363, 57215.14177287261, 6778.7288508459105, 7505.3620442260835, 7704.345130663733, 7721.420893432903, 7860.764177074697, 10079.69760907174, 12325.50697972071, 12403.131780775258, 12680.0, 12793.808092878427, 10434.58261669555, 10641.095506882002, 10659.0, 10684.09185219234, 11013.614523491638, 9000.954995224982, 9238.538073096184, 9806.950965245129, 9948.910728747043, 10139.087482126644, 9813.11681948628, 10145.442000689738, 10202.234832387452, 10249.692509224735, 10345.327553708985, 7130.037444944866, 7203.2076471589135, 7320.133365991638, 7324.63376831167, 7425.554466732014, 8101.473404228706, 10140.594376224868, 10141.543630536791, 10283.794324113625, 10309.123724483203], "value": 8111.179952862893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1376.8967327450441, 1405.0796728143066, 1483.940642374305, 1675.405231142945, 1703.880728349016, 6534.085228068075, 6902.723891044358, 7038.120234970629, 7379.1145062592495, 7379.520331178476, 7291.343779059884, 7439.070116235471, 7509.624518774053, 7551.24487551246, 7571.280728330799, 2142.068200332856, 2166.458385403649, 2182.378022263655, 2189.7481789594162, 2195.2316689158824, 2129.882856442895, 2144.592527419793, 2219.178903805594, 2224.132588290568, 2227.042371780134, 2168.4687251623373, 2172.0660116150048, 2181.0621432783896, 2188.7154669892893, 2216.3461778775218, 2120.088362004345, 2141.475338542067, 2144.5925274197853, 2153.3001774423296, 2159.2334721173916, 2081.396395045435, 2122.373899699592, 2133.232036466871, 2133.4133113393787, 2138.786121387864, 7394.2975417335265, 7467.066616672916, 7470.252974702486, 7486.401087913008, 7495.175530691619, 4309.1381723655395, 4388.078503514266, 4415.227335216357, 4418.911621767579, 4429.557044295577, 2773.833569985807, 2781.652293463317, 2820.294926268433, 2852.343960889007, 2890.56641503774, 822.6627082895984, 834.4284165346696, 883.0771843423573, 903.5843511984496, 904.3759805734088, 9830.311878168532, 9853.21174306061, 9871.210303175729, 9887.950560247153, 9900.60397584103, 3921.686265098785, 4025.355943049098, 4155.168966206771, 4163.0, 4169.60388763067, 8279.420440569224, 8290.544020078954, 8303.086660467343, 8308.169183081643, 8311.8337633247, 4537.659675524349, 4547.567981041796, 4564.3838081859185, 4567.0, 4568.1548913451115, 3686.576043754949, 4038.414429907647, 4192.7065105442325, 4198.517170525368, 4203.684723645678, 3520.0847779577493, 3538.4869193967247, 3577.94633080504, 3616.258666973264, 3631.1103779573646, 1130.4404319861721, 1158.6639874436487, 1158.8435561199299, 1173.501261963679, 1182.2138278045177, 719.7804669575829, 761.1551178192202, 787.5117427195061, 797.1231645190244, 813.8657121574873, 4611.100835337077, 4723.504032076583, 4748.074125545486, 4806.759662016955, 4881.0, 410.8130800485772, 419.645399637306, 419.90971941032825, 421.2396624093541, 429.0603578163811, 3066.5296224965305, 3107.192130046204, 3165.778395512292, 3203.439398105322, 3231.7091461768555, 8734.65061397537, 9063.909360906438, 9213.401128926598, 9223.44659320443, 9236.353455258068, 8831.249343805848, 8944.642214311485, 9077.183053525083, 9184.219341355925, 9212.078792120734, 7065.258147894499, 7089.645517724093, 7114.537555059008, 7117.288271172842, 7170.175429825533, 4533.116042371706, 4600.7469589172915, 4668.809453589313, 4681.110588988081, 4755.358026666494, 8366.958165209135, 8628.78428039304, 8632.395732298679, 8658.220760131366, 8690.304775618062, 8668.0898505656, 8683.261922736638, 8755.824883502422, 8779.561021949004, 8794.252488538546, 5607.523360514238, 6126.326104128453, 6376.171097757262, 6525.2169739631545, 6558.409743123142, 7181.102362204724, 7182.461315401261, 7204.639768011473, 7229.240929702306, 7321.450891183076, 6233.127362169391, 6439.420452159328, 6444.581102228341, 6681.0, 6794.796156115324, 6180.6600636965395, 6476.4495017924, 6492.64290464029, 6626.138601981712, 6665.966670166618, 5624.072028115479, 5744.0522313817755, 5764.510016648632, 5783.045797443375, 5798.043322851851, 4060.2488539620263, 4169.645580125723, 4205.243056249782, 4206.369044643328, 4351.282038463744, 4329.848455304049, 4362.890927726832, 4376.956230437655, 4445.510993790615, 4472.530384309601], "value": 3881.5236222663157, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.2451516707973, 293.3531562903797, 303.1844338728819, 309.6021612228287, 311.3120004789415, 5030.496950304968, 5049.596032317415, 5070.771815268313, 5082.822101226458, 5099.36257967754, 4993.651714037217, 4997.350344455225, 5000.974800165966, 5021.464160598902, 5023.221400682891, 3753.1555400035045, 3844.1735026969195, 3939.3500072488005, 3958.386450100239, 3976.6619837313865, 3944.9211015779665, 3946.48695669564, 3958.6342711764537, 3960.267350540145, 3964.484616999786, 3921.6274453926976, 3924.725269231155, 3957.9010524736823, 3961.584033676466, 3968.6428221460064, 3783.4892289540835, 3912.6283003114945, 3945.8618948336957, 3948.3287841066945, 3956.9010774730573, 3473.94789078164, 3485.3552092862647, 3487.3897068013, 3488.337215928965, 3490.5462289902403, 4813.951860481386, 4824.421069471651, 4881.267150161712, 4891.413030436335, 4903.754812259408, 4418.513963464016, 4475.0, 4504.346869703907, 4516.57092576208, 4530.79611417487, 5221.817236396708, 5225.111731005738, 5260.210968354739, 5260.684358938438, 5270.5520030797425, 3798.7274263121885, 3799.9430008549894, 3802.505674262356, 3823.560290566593, 3840.0207946973624, 5392.91910621345, 5512.310961129859, 5546.500814926676, 5556.749946252388, 5570.972145139248, 3911.6870650347905, 3914.236723838881, 3937.5078115235597, 3944.526656801202, 3950.9802450987563, 3984.4820173377275, 3991.481107456012, 3994.5206575210577, 4007.519097708294, 4014.6788256939376, 4494.258447356149, 4514.6614003949835, 4519.751413672279, 4524.25349817283, 4534.954650453519, 5155.922661160085, 5225.346831646044, 5311.7344132793205, 5312.415634280226, 5318.308619879468, 1361.9387127579282, 1370.3216907630785, 1381.716748066644, 1387.7293927684207, 1388.5004195468869, 858.6436628799104, 879.7404765594165, 891.2825175733467, 898.5237823953315, 910.4628269321, 4407.647388208936, 4541.023679908835, 4544.318352247124, 4566.49768525462, 4568.469562696534, 5376.1398176291605, 5377.27406800077, 5413.188021796762, 5415.783368665209, 5425.26758887553, 4343.67422443318, 4553.840615578439, 4586.495485496593, 4618.745968971672, 4618.930716039262, 2855.4289142171647, 2855.928601784972, 2864.3268831824503, 2873.5976963225166, 2880.5967164596973, 5020.723860187724, 5030.446650868402, 5033.546980771748, 5041.403677106293, 5056.82301119459, 5304.549113325334, 5536.2249285100115, 5540.418256083134, 5564.4713752193475, 5648.689322087324, 5027.396712394463, 5088.847334579968, 5114.48855114493, 5125.769340379692, 5154.14956532168, 5371.785128594813, 5454.154606036071, 5454.427285135003, 5457.536109430743, 5466.34403871538, 5263.499902527761, 5327.387350454747, 5338.423256418131, 5371.758270877742, 5419.485148910847, 5046.066477701637, 5053.393592768891, 5083.491650834887, 5083.694978301313, 5087.771050302671, 3940.842366305316, 3983.820728067186, 3989.800509974548, 3993.5607083221403, 4002.9192118128526, 5201.661891977009, 5360.16917377807, 5360.892782144413, 5372.086745253238, 5398.271233383443, 5166.796586116632, 5198.922016169836, 5220.269162317354, 5245.6852588844795, 5252.474752524717, 5220.0, 5230.503102205284, 5255.343082114736, 5255.78976840922, 5278.86802829917, 4916.541729135432, 5086.0, 5090.618203634668, 5093.337866077386, 5134.794608215779, 5093.745312734274, 5106.4893510648635, 5280.788768449219, 5301.840944771663, 5311.787528498817, 5126.846194614168, 5127.79488820443, 5144.202648589475, 5154.175331946871, 5165.586753059822], "value": 3972.1954495577784, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.5667316244441, 679.1578442731012, 683.3337495941457, 693.313619516678, 744.6276861569215, 10976.231663783528, 11467.483963221654, 11516.13628977827, 11516.30902145872, 11523.942380288086, 11296.491657875395, 11332.093432525398, 11337.546498140064, 11346.546138154465, 11349.716257093576, 629.0721186250267, 767.0833354141789, 773.3928865840317, 782.9451938364317, 793.8094857234252, 789.4986683456018, 792.7146227358176, 792.7978365516814, 792.9365650747956, 795.952242865427, 828.3000864269683, 830.3606223208126, 831.6922738586701, 833.2708879730236, 835.106436113359, 814.1858141858141, 819.1112642782529, 819.3608984991716, 820.0733171516208, 820.7578764264556, 604.3050491934267, 605.0651743056943, 605.4308949587373, 605.7637521366671, 607.9969600152015, 11702.53189872404, 11768.176227664067, 11787.528498860036, 11801.114916381306, 11828.881711182865, 13541.796873046913, 13625.454981800814, 13684.65788355289, 14184.929075354661, 14202.289885505787, 10232.334898231591, 10253.179745620333, 10383.532741026673, 10974.561017559288, 11102.833457498144, 4337.674674399433, 4384.0, 4387.758673272969, 4408.272635015219, 4502.5047244803045, 62285.0, 62755.37244627541, 62770.0, 63668.363316366704, 64377.0, 10803.081738052353, 11006.724831879266, 11101.333919964827, 11169.77742289656, 11257.774844503228, 21094.156233750786, 21328.14687412486, 21859.344219673436, 21946.341609751733, 21994.34016979493, 6515.566575353415, 6538.574992625464, 6610.107635469246, 6619.900701489481, 6760.695768690421, 3589.192431702846, 3644.635536446387, 3746.606606306353, 3765.4163604641326, 3853.6339047790416, 2895.290653789793, 2980.940381192364, 2985.686502917206, 2998.1005698290423, 3004.1288026472516, 502.99497005030213, 506.30888836738325, 511.79272394680214, 512.8794733237686, 524.1325606121875, 523.4137765702437, 579.1139556478595, 606.4996377988177, 606.9302030266576, 618.5051958433236, 8895.73312800617, 9097.09029097085, 9163.541822908961, 9264.629414823467, 9333.953330233442, 613.285522366447, 615.6860001399318, 619.9752009919553, 629.1129507394552, 650.3983814971133, 3981.5023122109737, 4033.4554835097474, 4097.262492751273, 4225.514065882401, 4329.935050974238, 54384.728076359366, 97996.53005204927, 100219.49890250502, 106740.46629766801, 110542.44728776305, 94796.52601737085, 95589.0, 95966.0, 97433.5384969226, 98098.0, 83391.58304208562, 84349.57825210835, 85548.57225713956, 85808.99999999875, 86452.13547864566, 8471.407001509835, 8969.14793094655, 9242.0, 9273.211776999031, 9882.901170988485, 29633.703662963093, 42494.0, 42573.57426425696, 43165.352519712855, 43266.783666081465, 62985.0, 64187.0, 64367.67816161077, 64671.0, 65220.0, 11513.942430287794, 11572.36352000648, 11804.82292765592, 11904.523819047488, 11950.760984780081, 13083.476660933457, 13303.135296205715, 13445.865541344852, 13541.05212635106, 13733.0, 12056.51773929033, 12613.74772504563, 12662.240265584094, 13335.66660833487, 13479.797803032767, 11524.94237528807, 11711.355875426932, 11811.291322520676, 12209.877901220874, 12248.755024899274, 12055.0, 12165.330906799856, 12561.120721549403, 12593.496260149492, 12966.157199781985, 9722.562484688064, 9852.950735246279, 10228.897711022795, 10437.478126093816, 10475.161987040867, 9688.224942004763, 9998.500074996076, 10450.16398688088, 10501.527431265764, 10746.462676866282], "value": 6760.807132137887, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 109.0, 116.0, 117.0, 117.0, 120.0, 114.0, 116.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 146.0, 177.0, 129.0, 152.0, 151.0, 125.0, 196.0, 124.0], "value": 147.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 163.0, 169.0, 176.0, 163.0, 164.0, 163.0, 165.0, 163.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 162.0, 77.0, 77.0, 76.0, 78.0, 162.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 150.0, 87.0, 86.0, 88.0, 135.0, 86.0, 86.0, 128.0], "value": 101.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 118.0, 117.0, 118.0, 117.0, 118.0, 118.0, 119.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 147.0, 149.0, 148.0, 149.0, 147.0, 148.0, 147.0, 149.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 63.0, 65.0, 64.0, 65.0, 65.0, 64.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 91.0, 82.0, 82.0, 79.0, 84.0, 86.0, 85.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 135.0, 138.0, 142.0, 139.0, 136.0, 142.0, 169.0, 139.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 167.0, 175.0, 174.0, 171.0, 174.0, 171.0, 169.0, 169.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 68.0, 70.0, 66.0, 67.0, 68.0, 68.0, 67.0], "value": 68.2, "unit": "score"}]}]} 09:23:41 INFO - Return code: 0 09:23:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:41 ERROR - # TBPL SUCCESS # 09:23:41 INFO - Running post-action listener: _resource_record_post_action 09:23:41 INFO - ##### 09:23:41 INFO - ##### Finished run-tests step (success) 09:23:41 INFO - ##### 09:23:41 INFO - Running post-run listener: _resource_record_post_run 09:23:42 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 542068736; Read time: 8; Write time: 456728 09:23:42 INFO - TinderboxPrint: CPU usage
13.3% 09:23:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:23:42 INFO - TinderboxPrint: I/O write bytes / time
542,068,736 / 456,728 09:23:42 INFO - TinderboxPrint: CPU idle
8,285.9 (86.7%) 09:23:42 INFO - TinderboxPrint: CPU user
1,220.2 (12.8%) 09:23:42 INFO - TinderboxPrint: Swap in / out
0 / 0 09:23:42 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246235136; Read time: 0; Write time: 229560 09:23:42 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 291217408; Read time: 0; Write time: 226764 09:23:42 INFO - Running post-run listener: _upload_blobber_files 09:23:42 INFO - Blob upload gear active. 09:23:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:42 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 09:23:42 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:42 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:23:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:23:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:43 INFO - (blobuploader) - INFO - Done attempting. 09:23:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:23:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:23:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:44 INFO - (blobuploader) - INFO - Done attempting. 09:23:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:48 INFO - (blobuploader) - INFO - Done attempting. 09:23:48 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:48 INFO - Return code: 0 09:23:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/60d2fccaf1c9949b588148764bd38c0f8dde27d6c9b8c9986974824a71a9b82d6124dcf7d39e36c41b3a0d38313b1d051975b65c6d32d2c935e206471efdf65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/763ae795827817193e941c1bdf25d6e4203d6ccee7c506df0d453fb01e82f45cab2d8332a54c23a114077f80d2985359fac1ca1243b10899aa177587c6261002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d45e438a00a633dd2265960eca9d15c5c6fcd861ee23fd9bbaf7be171264651d309597e075b4e8f51fda28abfcff50409ec9df5390686e450b99a6c3a0b73765"} 09:23:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:48 INFO - Contents: 09:23:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/60d2fccaf1c9949b588148764bd38c0f8dde27d6c9b8c9986974824a71a9b82d6124dcf7d39e36c41b3a0d38313b1d051975b65c6d32d2c935e206471efdf65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/763ae795827817193e941c1bdf25d6e4203d6ccee7c506df0d453fb01e82f45cab2d8332a54c23a114077f80d2985359fac1ca1243b10899aa177587c6261002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d45e438a00a633dd2265960eca9d15c5c6fcd861ee23fd9bbaf7be171264651d309597e075b4e8f51fda28abfcff50409ec9df5390686e450b99a6c3a0b73765"} 09:23:48 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:48 INFO - Copying logs to upload dir... 09:23:48 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.393606 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-11 09:23:47.722919) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 09:23:47.726443) ========= 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=652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/60d2fccaf1c9949b588148764bd38c0f8dde27d6c9b8c9986974824a71a9b82d6124dcf7d39e36c41b3a0d38313b1d051975b65c6d32d2c935e206471efdf65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/763ae795827817193e941c1bdf25d6e4203d6ccee7c506df0d453fb01e82f45cab2d8332a54c23a114077f80d2985359fac1ca1243b10899aa177587c6261002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d45e438a00a633dd2265960eca9d15c5c6fcd861ee23fd9bbaf7be171264651d309597e075b4e8f51fda28abfcff50409ec9df5390686e450b99a6c3a0b73765"} build_url:https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/C_JLetEGRqGbka1TfNEmsQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/60d2fccaf1c9949b588148764bd38c0f8dde27d6c9b8c9986974824a71a9b82d6124dcf7d39e36c41b3a0d38313b1d051975b65c6d32d2c935e206471efdf65e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/763ae795827817193e941c1bdf25d6e4203d6ccee7c506df0d453fb01e82f45cab2d8332a54c23a114077f80d2985359fac1ca1243b10899aa177587c6261002", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d45e438a00a633dd2265960eca9d15c5c6fcd861ee23fd9bbaf7be171264651d309597e075b4e8f51fda28abfcff50409ec9df5390686e450b99a6c3a0b73765"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 09:23:47.753548) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:23:47.754086) ========= 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=652eb03db935cfc1c15ebda0000001ad-1468252989.958829-1113013568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:23:47.836735) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 09:23:47.837051) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 09:23:47.837324) ========= ========= Total master_lag: 0.18 =========