builder: autoland_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-056 starttime: 1468301340.67 results: success (0) buildid: 20160711210025 builduid: a5afedd41cf341e297f293318aa28a0e revision: a4113131625db23e9bc749822e009e17f4d40384 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.673913) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.674390) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.674748) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.733730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.734049) ========= 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=52faae308a556c440401f8e5000001e8-1468299872.317980-770607251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 22:29:01-- 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% 46.4M=0s 2016-07-11 22:29:01 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155869 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.915580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.915903) ========= 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=52faae308a556c440401f8e5000001e8-1468299872.317980-770607251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021046 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:00.965888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 22:29:00.966308) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a4113131625db23e9bc749822e009e17f4d40384 --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 a4113131625db23e9bc749822e009e17f4d40384 --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=52faae308a556c440401f8e5000001e8-1468299872.317980-770607251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 22:29:02,114 truncating revision to first 12 chars 2016-07-11 22:29:02,114 Setting DEBUG logging. 2016-07-11 22:29:02,114 attempt 1/10 2016-07-11 22:29:02,114 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a4113131625d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 22:29:03,428 unpacking tar archive at: autoland-a4113131625d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.650455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 22:29:02.634138) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:02.634594) ========= script_repo_revision: a4113131625db23e9bc749822e009e17f4d40384 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:02.635148) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:02.635530) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 22:29:02.645986) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-11 22:29:02.646294) ========= /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=52faae308a556c440401f8e5000001e8-1468299872.317980-770607251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:29:03 INFO - MultiFileLogger online at 20160711 22:29:03 in /builds/slave/test 22:29:03 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 22:29:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:29:03 INFO - {'append_to_log': False, 22:29:03 INFO - 'base_work_dir': '/builds/slave/test', 22:29:03 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 22:29:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:29:03 INFO - 'branch': 'Autoland-Non-PGO', 22:29:03 INFO - 'buildbot_json_path': 'buildprops.json', 22:29:03 INFO - 'config_files': ('talos/linux_config.py',), 22:29:03 INFO - 'default_actions': ('clobber', 22:29:03 INFO - 'read-buildbot-config', 22:29:03 INFO - 'download-and-extract', 22:29:03 INFO - 'populate-webroot', 22:29:03 INFO - 'create-virtualenv', 22:29:03 INFO - 'install', 22:29:03 INFO - 'run-tests'), 22:29:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:29:03 INFO - 'download_minidump_stackwalk': True, 22:29:03 INFO - 'download_symbols': 'ondemand', 22:29:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:29:03 INFO - 'tooltool.py': '/tools/tooltool.py', 22:29:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:29:03 INFO - '/tools/misc-python/virtualenv.py')}, 22:29:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:29:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:29:03 INFO - 'installer_path': 'installer.exe', 22:29:03 INFO - 'log_level': 'info', 22:29:03 INFO - 'log_name': 'talos', 22:29:03 INFO - 'log_to_console': True, 22:29:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:29:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:29:03 INFO - 'opt_config_files': (), 22:29:03 INFO - 'pip_index': False, 22:29:03 INFO - 'sps_profile': False, 22:29:03 INFO - 'sps_profile_interval': 0, 22:29:03 INFO - 'suite': 'dromaeojs-e10s', 22:29:03 INFO - 'system_bits': '32', 22:29:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:29:03 INFO - 'title': 'talos-linux64-ix-056', 22:29:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:29:03 INFO - 'use_talos_json': True, 22:29:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:29:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:29:03 INFO - 'work_dir': 'build'} 22:29:03 INFO - ##### 22:29:03 INFO - ##### Running clobber step. 22:29:03 INFO - ##### 22:29:03 INFO - Running pre-action listener: _resource_record_pre_action 22:29:03 INFO - Running main action method: clobber 22:29:03 INFO - rmtree: /builds/slave/test/build 22:29:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:29:04 INFO - Running post-action listener: _resource_record_post_action 22:29:04 INFO - ##### 22:29:04 INFO - ##### Finished clobber step (success) 22:29:04 INFO - ##### 22:29:04 INFO - ##### 22:29:04 INFO - ##### Running read-buildbot-config step. 22:29:04 INFO - ##### 22:29:04 INFO - Running pre-action listener: _resource_record_pre_action 22:29:04 INFO - Running main action method: read_buildbot_config 22:29:04 INFO - Using buildbot properties: 22:29:04 INFO - { 22:29:04 INFO - "project": "", 22:29:04 INFO - "product": "firefox", 22:29:04 INFO - "script_repo_revision": "production", 22:29:04 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 22:29:04 INFO - "repository": "", 22:29:04 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs-e10s", 22:29:04 INFO - "buildid": "20160711210025", 22:29:04 INFO - "pgo_build": "False", 22:29:04 INFO - "basedir": "/builds/slave/test", 22:29:04 INFO - "buildnumber": 52, 22:29:04 INFO - "slavename": "talos-linux64-ix-056", 22:29:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:29:04 INFO - "platform": "ubuntu64_hw", 22:29:04 INFO - "scheduler": "tests-autoland-linux64-talos", 22:29:04 INFO - "branch": "autoland", 22:29:04 INFO - "repo_path": "integration/autoland", 22:29:04 INFO - "revision": "a4113131625db23e9bc749822e009e17f4d40384", 22:29:04 INFO - "stage_platform": "linux64", 22:29:04 INFO - "builduid": "a5afedd41cf341e297f293318aa28a0e", 22:29:04 INFO - "slavebuilddir": "test" 22:29:04 INFO - } 22:29:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:29:04 INFO - Running post-action listener: _resource_record_post_action 22:29:04 INFO - ##### 22:29:04 INFO - ##### Finished read-buildbot-config step (success) 22:29:04 INFO - ##### 22:29:04 INFO - ##### 22:29:04 INFO - ##### Running download-and-extract step. 22:29:04 INFO - ##### 22:29:04 INFO - Running pre-action listener: _resource_record_pre_action 22:29:04 INFO - Running main action method: download_and_extract 22:29:04 INFO - mkdir: /builds/slave/test/build/tests 22:29:04 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')]} 22:29:04 INFO - https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:29:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:29:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:29:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:08 INFO - Downloaded 1361 bytes. 22:29:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:29:08 INFO - Using the following test package requirements: 22:29:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:29:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:29:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:29:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'jsshell-linux-x86_64.zip'], 22:29:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:29:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:29:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:29:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:29:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:29:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:29:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:29:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:29:08 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')]} 22:29:08 INFO - https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:29:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:29:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:29:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:15 INFO - Downloaded 22700541 bytes. 22:29:15 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'] 22:29:15 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 22:29:16 INFO - Return code: 0 22:29: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 22:29: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')]} 22:29:16 INFO - https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:29:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:29:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:29:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:18 INFO - Downloaded 22700541 bytes. 22:29:18 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'] 22:29:18 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 22:29:20 INFO - Return code: 0 22:29:20 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')]} 22:29:20 INFO - https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:29:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:29:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:29:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:21 INFO - Downloaded 14403333 bytes. 22:29:21 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'] 22:29:21 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 22:29:21 INFO - Return code: 0 22:29:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:29:21 INFO - https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aItLOdztQ0iwVNw4GmRe4A/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 22:29:22 INFO - Downloaded 56654029 bytes. 22:29:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:29:22 INFO - mkdir: /builds/slave/test/properties 22:29:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:29:22 INFO - Writing to file /builds/slave/test/properties/build_url 22:29:22 INFO - Contents: 22:29:22 INFO - build_url:https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:29:23 INFO - Running post-action listener: _resource_record_post_action 22:29:23 INFO - Running post-action listener: set_extra_try_arguments 22:29:23 INFO - ##### 22:29:23 INFO - ##### Finished download-and-extract step (success) 22:29:23 INFO - ##### 22:29:23 INFO - ##### 22:29:23 INFO - ##### Running populate-webroot step. 22:29:23 INFO - ##### 22:29:23 INFO - Running pre-action listener: _resource_record_pre_action 22:29:23 INFO - Running main action method: populate_webroot 22:29:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:29:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:29:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:29:23 INFO - '--tppagecycles', 22:29:23 INFO - '7'], 22:29:23 INFO - 'tests': ['tsvgm']}}, 22:29:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:29:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:29:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:29:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:29:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:29:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:29:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['damp', 'tps']}, 22:29:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'tests': ['damp', 'tps']}, 22:29:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['dromaeo_dom']}, 22:29:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:29:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['basic_compositor_video']}, 22:29:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:29:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['a11yr', 22:29:23 INFO - 'ts_paint', 22:29:23 INFO - 'tpaint', 22:29:23 INFO - 'sessionrestore', 22:29:23 INFO - 'sessionrestore_no_auto_restore', 22:29:23 INFO - 'tabpaint']}, 22:29:23 INFO - 'other-e10s': {'tests': ['a11yr', 22:29:23 INFO - 'ts_paint', 22:29:23 INFO - 'tpaint', 22:29:23 INFO - 'sessionrestore', 22:29:23 INFO - 'sessionrestore_no_auto_restore', 22:29:23 INFO - 'tabpaint']}, 22:29:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:29:23 INFO - 'ts_paint', 22:29:23 INFO - 'tpaint', 22:29:23 INFO - 'sessionrestore', 22:29:23 INFO - 'sessionrestore_no_auto_restore', 22:29:23 INFO - 'tabpaint']}, 22:29:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:29:23 INFO - 'ts_paint', 22:29:23 INFO - 'tpaint', 22:29:23 INFO - 'sessionrestore', 22:29:23 INFO - 'sessionrestore_no_auto_restore', 22:29:23 INFO - 'tabpaint']}, 22:29:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['a11yr', 22:29:23 INFO - 'ts_paint', 22:29:23 INFO - 'tpaint', 22:29:23 INFO - 'sessionrestore', 22:29:23 INFO - 'sessionrestore_no_auto_restore', 22:29:23 INFO - 'tabpaint']}, 22:29:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['a11yr', 22:29:23 INFO - 'ts_paint', 22:29:23 INFO - 'tpaint', 22:29:23 INFO - 'sessionrestore', 22:29:23 INFO - 'sessionrestore_no_auto_restore', 22:29:23 INFO - 'tabpaint']}, 22:29:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['tsvgx', 22:29:23 INFO - 'tsvgr_opacity', 22:29:23 INFO - 'tart', 22:29:23 INFO - 'tscrollx', 22:29:23 INFO - 'cart']}, 22:29:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:29:23 INFO - 'tsvgr_opacity', 22:29:23 INFO - 'tart', 22:29:23 INFO - 'tscrollx', 22:29:23 INFO - 'cart']}, 22:29:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'talos_options': ['--disable-e10s'], 22:29:23 INFO - 'tests': ['tp5o']}, 22:29:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'tests': ['tp5o']}, 22:29:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'talos_options': ['--disable-e10s', 22:29:23 INFO - '--xperf_path', 22:29:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:29:23 INFO - 'tests': ['tp5n']}, 22:29:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:29:23 INFO - 'talos_options': ['--xperf_path', 22:29:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:29:23 INFO - 'tests': ['tp5n']}}, 22:29:23 INFO - 'talos.zip': {'path': '', 22:29:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:29:23 INFO - Running post-action listener: _resource_record_post_action 22:29:23 INFO - ##### 22:29:23 INFO - ##### Finished populate-webroot step (success) 22:29:23 INFO - ##### 22:29:23 INFO - ##### 22:29:23 INFO - ##### Running create-virtualenv step. 22:29:23 INFO - ##### 22:29:23 INFO - Running pre-action listener: _resource_record_pre_action 22:29:23 INFO - Running main action method: create_virtualenv 22:29:23 INFO - Creating virtualenv /builds/slave/test/build/venv 22:29:23 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 22:29:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:29:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:29:23 INFO - Using real prefix '/usr' 22:29:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:29:24 INFO - Installing distribute.............................................................................................................................................................................................done. 22:29:26 INFO - Installing pip.................done. 22:29:26 INFO - Return code: 0 22:29:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:29:26 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')]} 22:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:26 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')]} 22:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:26 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:26 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 22:29:26 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 22:29:26 INFO - Using env: {'DISPLAY': ':0', 22:29:26 INFO - 'HOME': '/home/cltbld', 22:29:26 INFO - 'LANG': 'en_US.UTF-8', 22:29:26 INFO - 'LANGUAGE': 'en_US:en', 22:29:26 INFO - 'LOGNAME': 'cltbld', 22:29:26 INFO - 'MAIL': '/var/mail/cltbld', 22:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:26 INFO - 'MOZ_NO_REMOTE': '1', 22:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:26 INFO - 'NO_EM_RESTART': '1', 22:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:26 INFO - 'PWD': '/builds/slave/test', 22:29:26 INFO - 'SHELL': '/bin/bash', 22:29:26 INFO - 'SHLVL': '1', 22:29:26 INFO - 'TERM': 'linux', 22:29:26 INFO - 'TMOUT': '86400', 22:29:26 INFO - 'USER': 'cltbld', 22:29:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:26 INFO - '_': '/tools/buildbot/bin/python'} 22:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:26 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 22:29:26 INFO - Cleaning up... 22:29:26 INFO - Return code: 0 22:29:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:29:26 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')]} 22:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:26 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')]} 22:29:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:26 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:26 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 22:29:26 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 22:29:26 INFO - Using env: {'DISPLAY': ':0', 22:29:26 INFO - 'HOME': '/home/cltbld', 22:29:26 INFO - 'LANG': 'en_US.UTF-8', 22:29:26 INFO - 'LANGUAGE': 'en_US:en', 22:29:26 INFO - 'LOGNAME': 'cltbld', 22:29:26 INFO - 'MAIL': '/var/mail/cltbld', 22:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:26 INFO - 'MOZ_NO_REMOTE': '1', 22:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:26 INFO - 'NO_EM_RESTART': '1', 22:29:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:26 INFO - 'PWD': '/builds/slave/test', 22:29:26 INFO - 'SHELL': '/bin/bash', 22:29:26 INFO - 'SHLVL': '1', 22:29:26 INFO - 'TERM': 'linux', 22:29:26 INFO - 'TMOUT': '86400', 22:29:26 INFO - 'USER': 'cltbld', 22:29:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:26 INFO - '_': '/tools/buildbot/bin/python'} 22:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:26 INFO - Downloading/unpacking psutil>=3.1.1 22:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:29:26 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 22:29:26 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 22:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:29:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:29:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:29:28 INFO - Installing collected packages: psutil 22:29:28 INFO - Running setup.py install for psutil 22:29:28 INFO - building 'psutil._psutil_linux' extension 22:29:28 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 22:29:28 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 22:29:28 INFO - building 'psutil._psutil_posix' extension 22:29:28 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 22:29:29 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 22:29:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:29:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:29:29 INFO - Successfully installed psutil 22:29:29 INFO - Cleaning up... 22:29:29 INFO - Return code: 0 22:29:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:29: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')]} 22:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29: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')]} 22:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:29: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 mozsystemmonitor==0.3 22:29:29 INFO - Using env: {'DISPLAY': ':0', 22:29:29 INFO - 'HOME': '/home/cltbld', 22:29:29 INFO - 'LANG': 'en_US.UTF-8', 22:29:29 INFO - 'LANGUAGE': 'en_US:en', 22:29:29 INFO - 'LOGNAME': 'cltbld', 22:29:29 INFO - 'MAIL': '/var/mail/cltbld', 22:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:29 INFO - 'MOZ_NO_REMOTE': '1', 22:29:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:29 INFO - 'NO_EM_RESTART': '1', 22:29:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:29 INFO - 'PWD': '/builds/slave/test', 22:29:29 INFO - 'SHELL': '/bin/bash', 22:29:29 INFO - 'SHLVL': '1', 22:29:29 INFO - 'TERM': 'linux', 22:29:29 INFO - 'TMOUT': '86400', 22:29:29 INFO - 'USER': 'cltbld', 22:29:29 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:29 INFO - '_': '/tools/buildbot/bin/python'} 22:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:29:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:29:31 INFO - Installing collected packages: mozsystemmonitor 22:29:31 INFO - Running setup.py install for mozsystemmonitor 22:29:31 INFO - Successfully installed mozsystemmonitor 22:29:31 INFO - Cleaning up... 22:29:31 INFO - Return code: 0 22:29:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:29:31 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')]} 22:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:31 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')]} 22:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:31 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:31 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 22:29:31 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 22:29:31 INFO - Using env: {'DISPLAY': ':0', 22:29:31 INFO - 'HOME': '/home/cltbld', 22:29:31 INFO - 'LANG': 'en_US.UTF-8', 22:29:31 INFO - 'LANGUAGE': 'en_US:en', 22:29:31 INFO - 'LOGNAME': 'cltbld', 22:29:31 INFO - 'MAIL': '/var/mail/cltbld', 22:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:31 INFO - 'MOZ_NO_REMOTE': '1', 22:29:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:31 INFO - 'NO_EM_RESTART': '1', 22:29:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:31 INFO - 'PWD': '/builds/slave/test', 22:29:31 INFO - 'SHELL': '/bin/bash', 22:29:31 INFO - 'SHLVL': '1', 22:29:31 INFO - 'TERM': 'linux', 22:29:31 INFO - 'TMOUT': '86400', 22:29:31 INFO - 'USER': 'cltbld', 22:29:31 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:31 INFO - '_': '/tools/buildbot/bin/python'} 22:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:31 INFO - Downloading/unpacking jsonschema==2.5.1 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:29:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:29:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:29:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:29:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:29:33 INFO - Downloading functools32-3.2.3-2.tar.gz 22:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:29:33 INFO - warning: no files found matching '*.txt' 22:29:33 INFO - no previously-included directories found matching 'build' 22:29:33 INFO - no previously-included directories found matching 'dist' 22:29:33 INFO - no previously-included directories found matching '.git*' 22:29:33 INFO - Installing collected packages: jsonschema, functools32 22:29:33 INFO - Running setup.py install for functools32 22:29:33 INFO - warning: no files found matching '*.txt' 22:29:33 INFO - no previously-included directories found matching 'build' 22:29:33 INFO - no previously-included directories found matching 'dist' 22:29:33 INFO - no previously-included directories found matching '.git*' 22:29:34 INFO - Successfully installed jsonschema functools32 22:29:34 INFO - Cleaning up... 22:29:34 INFO - Return code: 0 22:29:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:29:34 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')]} 22:29:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:34 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')]} 22:29:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:34 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:34 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 22:29:34 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 22:29:34 INFO - Using env: {'DISPLAY': ':0', 22:29:34 INFO - 'HOME': '/home/cltbld', 22:29:34 INFO - 'LANG': 'en_US.UTF-8', 22:29:34 INFO - 'LANGUAGE': 'en_US:en', 22:29:34 INFO - 'LOGNAME': 'cltbld', 22:29:34 INFO - 'MAIL': '/var/mail/cltbld', 22:29:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:34 INFO - 'MOZ_NO_REMOTE': '1', 22:29:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:34 INFO - 'NO_EM_RESTART': '1', 22:29:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:34 INFO - 'PWD': '/builds/slave/test', 22:29:34 INFO - 'SHELL': '/bin/bash', 22:29:34 INFO - 'SHLVL': '1', 22:29:34 INFO - 'TERM': 'linux', 22:29:34 INFO - 'TMOUT': '86400', 22:29:34 INFO - 'USER': 'cltbld', 22:29:34 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:34 INFO - '_': '/tools/buildbot/bin/python'} 22:29:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:34 INFO - Downloading/unpacking blobuploader==1.2.4 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29:36 INFO - Downloading blobuploader-1.2.4.tar.gz 22:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:29:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:29:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 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 22:29:36 INFO - Downloading docopt-0.6.1.tar.gz 22:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:29:36 INFO - Installing collected packages: blobuploader, requests, docopt 22:29:36 INFO - Running setup.py install for blobuploader 22:29:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:29:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:29:37 INFO - Running setup.py install for requests 22:29:37 INFO - Running setup.py install for docopt 22:29:37 INFO - Successfully installed blobuploader requests docopt 22:29:37 INFO - Cleaning up... 22:29:37 INFO - Return code: 0 22:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:29:37 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')]} 22:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:37 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')]} 22:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:37 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:37 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 22:29:37 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 22:29:37 INFO - Using env: {'DISPLAY': ':0', 22:29:37 INFO - 'HOME': '/home/cltbld', 22:29:37 INFO - 'LANG': 'en_US.UTF-8', 22:29:37 INFO - 'LANGUAGE': 'en_US:en', 22:29:37 INFO - 'LOGNAME': 'cltbld', 22:29:37 INFO - 'MAIL': '/var/mail/cltbld', 22:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:37 INFO - 'MOZ_NO_REMOTE': '1', 22:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:37 INFO - 'NO_EM_RESTART': '1', 22:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:37 INFO - 'PWD': '/builds/slave/test', 22:29:37 INFO - 'SHELL': '/bin/bash', 22:29:37 INFO - 'SHLVL': '1', 22:29:37 INFO - 'TERM': 'linux', 22:29:37 INFO - 'TMOUT': '86400', 22:29:37 INFO - 'USER': 'cltbld', 22:29:37 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:37 INFO - '_': '/tools/buildbot/bin/python'} 22:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:29:37 INFO - Running setup.py (path:/tmp/pip-C4KEWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:29:37 INFO - Running setup.py (path:/tmp/pip-5kFPAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:29:37 INFO - Running setup.py (path:/tmp/pip-bQ7LNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:29:37 INFO - Running setup.py (path:/tmp/pip-VKWxKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:29:38 INFO - Running setup.py (path:/tmp/pip-25sjDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:29:38 INFO - Running setup.py (path:/tmp/pip-tnH7Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:29:38 INFO - Running setup.py (path:/tmp/pip-lFYJih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:29:38 INFO - Running setup.py (path:/tmp/pip-ChmdYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:29:38 INFO - Running setup.py (path:/tmp/pip-uPvB2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:29:38 INFO - Running setup.py (path:/tmp/pip-ym66Hu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:29:38 INFO - Running setup.py (path:/tmp/pip-P82Jhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:29:38 INFO - Running setup.py (path:/tmp/pip-1DIwFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:29:38 INFO - Running setup.py (path:/tmp/pip-P6cWh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:29:38 INFO - Running setup.py (path:/tmp/pip-kk6eRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:29:38 INFO - Running setup.py (path:/tmp/pip-_kViCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:29:38 INFO - Running setup.py (path:/tmp/pip-YOJ1GY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:29:38 INFO - Running setup.py (path:/tmp/pip-ECGzbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:29:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:29:38 INFO - Running setup.py install for manifestparser 22:29:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Running setup.py install for mozcrash 22:29:39 INFO - Running setup.py install for mozdebug 22:29:39 INFO - Running setup.py install for mozdevice 22:29:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Running setup.py install for mozfile 22:29:39 INFO - Running setup.py install for mozhttpd 22:29:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Running setup.py install for mozinfo 22:29:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Running setup.py install for mozInstall 22:29:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:29:39 INFO - Running setup.py install for mozleak 22:29:39 INFO - Running setup.py install for mozlog 22:29:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Running setup.py install for moznetwork 22:29:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Running setup.py install for mozprocess 22:29:40 INFO - Running setup.py install for mozprofile 22:29:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Running setup.py install for mozrunner 22:29:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Running setup.py install for mozscreenshot 22:29:40 INFO - Running setup.py install for moztest 22:29:40 INFO - Running setup.py install for mozversion 22:29:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:29:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:29:40 INFO - Cleaning up... 22:29:40 INFO - Return code: 0 22:29:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:29:40 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')]} 22:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:40 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')]} 22:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:40 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:40 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 22:29:40 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 22:29:40 INFO - Using env: {'DISPLAY': ':0', 22:29:40 INFO - 'HOME': '/home/cltbld', 22:29:40 INFO - 'LANG': 'en_US.UTF-8', 22:29:40 INFO - 'LANGUAGE': 'en_US:en', 22:29:40 INFO - 'LOGNAME': 'cltbld', 22:29:40 INFO - 'MAIL': '/var/mail/cltbld', 22:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:40 INFO - 'MOZ_NO_REMOTE': '1', 22:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:40 INFO - 'NO_EM_RESTART': '1', 22:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:40 INFO - 'PWD': '/builds/slave/test', 22:29:40 INFO - 'SHELL': '/bin/bash', 22:29:40 INFO - 'SHLVL': '1', 22:29:40 INFO - 'TERM': 'linux', 22:29:40 INFO - 'TMOUT': '86400', 22:29:40 INFO - 'USER': 'cltbld', 22:29:40 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:40 INFO - '_': '/tools/buildbot/bin/python'} 22:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:29:41 INFO - Running setup.py (path:/tmp/pip-X3B4B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:29:41 INFO - Running setup.py (path:/tmp/pip-AVR_9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:29:41 INFO - Running setup.py (path:/tmp/pip-D6jmKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:29:41 INFO - Running setup.py (path:/tmp/pip-khtwgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:29:41 INFO - Running setup.py (path:/tmp/pip-ElG6V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:29:41 INFO - Running setup.py (path:/tmp/pip-Ht_Gi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:29:41 INFO - Running setup.py (path:/tmp/pip-NPRkB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:29:41 INFO - Running setup.py (path:/tmp/pip-975Ofe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:29:41 INFO - Running setup.py (path:/tmp/pip-4TktX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:29:41 INFO - Running setup.py (path:/tmp/pip-n9K_ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:29:41 INFO - Running setup.py (path:/tmp/pip-HoiXpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:29:41 INFO - Running setup.py (path:/tmp/pip-w0f7_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:29:41 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)) 22:29:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:29:41 INFO - Running setup.py (path:/tmp/pip-opp5dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:29:42 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)) 22:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:29:42 INFO - Running setup.py (path:/tmp/pip-ZI3cu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:29:42 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)) 22:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:29:42 INFO - Running setup.py (path:/tmp/pip-sCxxSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:29:42 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)) 22:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:29:42 INFO - Running setup.py (path:/tmp/pip-3jZ4BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:29:42 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)) 22:29:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:29:42 INFO - Running setup.py (path:/tmp/pip-1hnWmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:29:42 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)) 22:29:42 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)) 22:29:42 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)) 22:29:42 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)) 22:29:42 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)) 22:29:42 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)) 22:29:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:44 INFO - Downloading blessings-1.6.tar.gz 22:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:29:44 INFO - Installing collected packages: blessings 22:29:44 INFO - Running setup.py install for blessings 22:29:44 INFO - Successfully installed blessings 22:29:44 INFO - Cleaning up... 22:29:44 INFO - Return code: 0 22:29:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:29:44 INFO - Reading from file tmpfile_stdout 22:29:44 INFO - Current package versions: 22:29:44 INFO - argparse == 1.2.1 22:29:44 INFO - blessings == 1.6 22:29:44 INFO - blobuploader == 1.2.4 22:29:44 INFO - docopt == 0.6.1 22:29:44 INFO - functools32 == 3.2.3-2 22:29:44 INFO - jsonschema == 2.5.1 22:29:44 INFO - manifestparser == 1.1 22:29:44 INFO - mozInstall == 1.12 22:29:44 INFO - mozcrash == 0.17 22:29:44 INFO - mozdebug == 0.1 22:29:44 INFO - mozdevice == 0.48 22:29:44 INFO - mozfile == 1.2 22:29:44 INFO - mozhttpd == 0.7 22:29:44 INFO - mozinfo == 0.9 22:29:44 INFO - mozleak == 0.1 22:29:44 INFO - mozlog == 3.2 22:29:44 INFO - moznetwork == 0.27 22:29:44 INFO - mozprocess == 0.23 22:29:44 INFO - mozprofile == 0.28 22:29:44 INFO - mozrunner == 6.12 22:29:44 INFO - mozscreenshot == 0.1 22:29:44 INFO - mozsystemmonitor == 0.3 22:29:44 INFO - moztest == 0.7 22:29:44 INFO - mozversion == 1.4 22:29:44 INFO - psutil == 3.1.1 22:29:44 INFO - requests == 1.2.3 22:29:44 INFO - wsgiref == 0.1.2 22:29:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:29:44 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')]} 22:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:44 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')]} 22:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:44 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:44 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 22:29:44 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 22:29:44 INFO - Using env: {'DISPLAY': ':0', 22:29:44 INFO - 'HOME': '/home/cltbld', 22:29:44 INFO - 'LANG': 'en_US.UTF-8', 22:29:44 INFO - 'LANGUAGE': 'en_US:en', 22:29:44 INFO - 'LOGNAME': 'cltbld', 22:29:44 INFO - 'MAIL': '/var/mail/cltbld', 22:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:44 INFO - 'MOZ_NO_REMOTE': '1', 22:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:44 INFO - 'NO_EM_RESTART': '1', 22:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:44 INFO - 'PWD': '/builds/slave/test', 22:29:44 INFO - 'SHELL': '/bin/bash', 22:29:44 INFO - 'SHLVL': '1', 22:29:44 INFO - 'TERM': 'linux', 22:29:44 INFO - 'TMOUT': '86400', 22:29:44 INFO - 'USER': 'cltbld', 22:29:44 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:44 INFO - '_': '/tools/buildbot/bin/python'} 22:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 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)) 22:29:44 INFO - Cleaning up... 22:29:44 INFO - Return code: 0 22:29:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:29:44 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')]} 22:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:44 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')]} 22:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:44 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 0x2a76030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ca5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, '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': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', '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 22:29:44 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 22:29:44 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 22:29:44 INFO - Using env: {'DISPLAY': ':0', 22:29:44 INFO - 'HOME': '/home/cltbld', 22:29:44 INFO - 'LANG': 'en_US.UTF-8', 22:29:44 INFO - 'LANGUAGE': 'en_US:en', 22:29:44 INFO - 'LOGNAME': 'cltbld', 22:29:44 INFO - 'MAIL': '/var/mail/cltbld', 22:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:44 INFO - 'MOZ_NO_REMOTE': '1', 22:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:29:44 INFO - 'NO_EM_RESTART': '1', 22:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:44 INFO - 'PWD': '/builds/slave/test', 22:29:44 INFO - 'SHELL': '/bin/bash', 22:29:44 INFO - 'SHLVL': '1', 22:29:44 INFO - 'TERM': 'linux', 22:29:44 INFO - 'TMOUT': '86400', 22:29:44 INFO - 'USER': 'cltbld', 22:29:44 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:44 INFO - '_': '/tools/buildbot/bin/python'} 22:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:29:45 INFO - Cleaning up... 22:29:45 INFO - Return code: 0 22:29:45 INFO - Running post-action listener: _resource_record_post_action 22:29:45 INFO - Running post-action listener: _start_resource_monitoring 22:29:45 INFO - Starting resource monitoring. 22:29:45 INFO - ##### 22:29:45 INFO - ##### Finished create-virtualenv step (success) 22:29:45 INFO - ##### 22:29:45 INFO - ##### 22:29:45 INFO - ##### Running install step. 22:29:45 INFO - ##### 22:29:45 INFO - Running pre-action listener: _resource_record_pre_action 22:29:45 INFO - Running main action method: install 22:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:29:45 INFO - Reading from file tmpfile_stdout 22:29:45 INFO - Detecting whether we're running mozinstall >=1.0... 22:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:29:45 INFO - Reading from file tmpfile_stdout 22:29:45 INFO - Output received: 22:29:45 INFO - Usage: mozinstall [options] installer 22:29:45 INFO - Options: 22:29:45 INFO - -h, --help show this help message and exit 22:29:45 INFO - -d DEST, --destination=DEST 22:29:45 INFO - Directory to install application into. [default: 22:29:45 INFO - "/builds/slave/test"] 22:29:45 INFO - --app=APP Application being installed. [default: firefox] 22:29:45 INFO - mkdir: /builds/slave/test/build/application 22:29:45 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'] 22:29:45 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 22:30:01 INFO - Reading from file tmpfile_stdout 22:30:01 INFO - Output received: 22:30:01 INFO - /builds/slave/test/build/application/firefox/firefox 22:30:01 INFO - Running post-action listener: _resource_record_post_action 22:30:01 INFO - ##### 22:30:01 INFO - ##### Finished install step (success) 22:30:01 INFO - ##### 22:30:01 INFO - ##### 22:30:01 INFO - ##### Running run-tests step. 22:30:01 INFO - ##### 22:30:01 INFO - Running pre-action listener: _resource_record_pre_action 22:30:01 INFO - Running main action method: run_tests 22:30:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:30:01 INFO - Python 2.7.3 22:30:01 INFO - Return code: 0 22:30:01 INFO - grabbing minidump binary from tooltool 22:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:30:01 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 0x2d34570>, '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 0x2d35470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d35900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:30:01 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 22:30:01 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 22:30:01 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 22:30:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:30:01 INFO - Return code: 0 22:30:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:30:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:30:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:30:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:30:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:30:01 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/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 22:30:01 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/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 22:30:01 INFO - Using env: {'DISPLAY': ':0', 22:30:01 INFO - 'HOME': '/home/cltbld', 22:30:01 INFO - 'LANG': 'en_US.UTF-8', 22:30:01 INFO - 'LANGUAGE': 'en_US:en', 22:30:01 INFO - 'LOGNAME': 'cltbld', 22:30:01 INFO - 'MAIL': '/var/mail/cltbld', 22:30:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:30:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:01 INFO - 'MOZ_NO_REMOTE': '1', 22:30:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:30:01 INFO - 'NO_EM_RESTART': '1', 22:30:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:01 INFO - 'PWD': '/builds/slave/test', 22:30:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:30:01 INFO - 'SHELL': '/bin/bash', 22:30:01 INFO - 'SHLVL': '1', 22:30:01 INFO - 'TERM': 'linux', 22:30:01 INFO - 'TMOUT': '86400', 22:30:01 INFO - 'USER': 'cltbld', 22:30:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1468299872.317980-770607251', 22:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:01 INFO - '_': '/tools/buildbot/bin/python'} 22:30:01 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/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 22:30:02 INFO - mozversion application_buildid: 20160711210025 22:30:02 INFO - mozversion application_changeset: a4113131625db23e9bc749822e009e17f4d40384 22:30:02 INFO - mozversion application_display_name: Nightly 22:30:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:30:02 INFO - mozversion application_name: Firefox 22:30:02 INFO - mozversion application_remotingname: firefox 22:30:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:30:02 INFO - mozversion application_vendor: Mozilla 22:30:02 INFO - mozversion application_version: 50.0a1 22:30:02 INFO - mozversion platform_buildid: 20160711210025 22:30:02 INFO - mozversion platform_changeset: a4113131625db23e9bc749822e009e17f4d40384 22:30:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:30:02 INFO - mozversion platform_version: 50.0a1 22:30:02 INFO - using testdate: 1468301402 22:30:02 INFO - actual date: 1468301402 22:30:02 INFO - starting webserver on 'localhost:59225' 22:30:02 INFO - SUITE-START | Running 2 tests 22:30:02 INFO - TEST-START | dromaeo_css 22:30:02 INFO - operating with platform_type : linux_ 22:30:02 INFO - Initialising browser for dromaeo_css test... 22:30:02 INFO - TEST-INFO | started process 11550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWOzQT7/profile http://localhost:59225/getInfo.html) 22:30:07 INFO - PROCESS | 11550 | __metrics Screen width/height:1600/1200 22:30:07 INFO - PROCESS | 11550 | colorDepth:24 22:30:07 INFO - PROCESS | 11550 | Browser inner width/height: 1024/697 22:30:07 INFO - PROCESS | 11550 | __metrics 22:30:07 ERROR - PROCESS | 11550 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 22:30:08 INFO - TEST-INFO | 11550: exit 0 22:30:08 INFO - Browser initialized. 22:30:08 INFO - Running cycle 1/1 for dromaeo_css test... 22:30:08 INFO - TEST-INFO | started process 11672 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWOzQT7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:20 INFO - PROCESS | 11672 | RSS: Main: 168816640 22:30:20 INFO - PROCESS | 11672 | 22:31:10 INFO - PROCESS | 11672 | 1468301470299 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] 22:33:22 INFO - PROCESS | 11672 | Cycle 1(1): loaded http://localhost:59225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59225/tests/dromaeo/cssquery-ext.html) 22:33:23 INFO - PROCESS | 11672 | RSS: Main: 169324544 22:33:23 INFO - PROCESS | 11672 | 22:36:25 INFO - PROCESS | 11672 | Cycle 1(1): loaded http://localhost:59225/tests/dromaeo/cssquery-ext.html (next: http://localhost:59225/tests/dromaeo/cssquery-jquery.html) 22:36:26 INFO - PROCESS | 11672 | RSS: Main: 167972864 22:36:26 INFO - PROCESS | 11672 | 22:39:28 INFO - PROCESS | 11672 | Cycle 1(1): loaded http://localhost:59225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59225/tests/dromaeo/cssquery-mootools.html) 22:39:29 INFO - PROCESS | 11672 | RSS: Main: 170807296 22:39:29 INFO - PROCESS | 11672 | 22:42:30 INFO - PROCESS | 11672 | Cycle 1(1): loaded http://localhost:59225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59225/tests/dromaeo/cssquery-prototype.html) 22:42:31 INFO - PROCESS | 11672 | RSS: Main: 166608896 22:42:31 INFO - PROCESS | 11672 | 22:45:34 INFO - PROCESS | 11672 | Cycle 1(1): loaded http://localhost:59225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59225/tests/dromaeo/cssquery-yui.html) 22:45:35 INFO - PROCESS | 11672 | RSS: Main: 168820736 22:45:35 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | Cycle 1(1): loaded http://localhost:59225/tests/dromaeo/cssquery-yui.html (next: http://localhost:59225/tests/dromaeo/cssquery-dojo.html) 22:48:37 INFO - PROCESS | 11672 | RSS: Main: 170168320 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | __start_tp_report 22:48:37 INFO - PROCESS | 11672 | _x_x_mozilla_page_load 22:48:37 INFO - PROCESS | 11672 | _x_x_mozilla_page_load_details 22:48:37 INFO - PROCESS | 11672 | |i|pagename|runs| 22:48:37 INFO - PROCESS | 11672 | |0;dojo.html;2602.895884164633;2653.1642532602227;2664.09420796929;2664.7335266473356;2667.186508115024;4691.179043667357;4775.49857264987;4776.116418462587;4868.245421959602;4875.683080599759;4712.222483290253;4797.4962628923895;4797.856064318067;4810.927836082453;4812.374391329122;4363.52001279859;4382.430284063067;4410.404595379631;4411.514733379326;4463.4420697412825;4381.058072514409;4387.1006443679125;4394.538573449774;4461.1523810476065;4493.168763778695;539.8991303468498;4398.318260669569;4434.290513517838;4443.800028998703;4495.730256184639;4398.7580683062415;4400.09797991411;4400.713953593006;4402.603765661106;4464.933026004613;4338.240807858644;4371.803268852877;4390.27560452525;4393;4417.4919884213405;4730.219513780222;4758.689846208838;4773.212419950704;4780.4741478437345;4793.089313030548;5077.035363281002;5103.336566246401;5110.693358398508;5117.744112794346;5139.794408223666;5160.896782064376;5170.689758614495;5174.197999310113;5175.948240517584;5182.274481572583;4663.27719203524;4693.272542755844;4761.761911904391;4775.2359622460235;4823.927641085386;5248.685078895279;5270.710110943858;5289.735513224324;5304.173202385888;5319.707416092152;4917.950820491821;5046.646734728533;5078.390593128848;5082.034413461431;5091.2363145527725;5063.468335824759;5097.515736005148;5135.589152867761;5145.974270128625;5173.508516690908;5092.007058623607;5109.923351149661;5110.233464980282;5121.513456221653;5167.612429067835;4892.2661600760175;4980.153373926441;5050.3434553507805;5051.671641343374;5065.290859279704;4145.2124096421585;4278.443802305743;4290.077633308853;4306.65546756265;4372;3713.2573485303046;3772.0946972726347;3775.565809931893;3783.2244389900006;3821.808909554511;4308.74147551148;4361.542038086019;4415.602595766396;4430.468343798765;4452.666050046259;5081.771320290596;5128.820491282787;5141.357330333708;5181.844544663591;5209.71346575942;4348.978255108705;4356.825726970886;4389.341598760211;4392.5168231495145;4406.581374769392;4482.7758612069265;4587.77061146948;4595.172868883633;4644.349791029259;4674.228752255908;4803.639727020487;4848.660593758403;4850.927236091391;4872.658913875995;4874.5856602189215;5153.639245252796;5158.484151584811;5160.432352441304;5197.298364720715;5214.9478505214465;4693.577578017995;4825.541573550507;4878.341423907727;4892.315075889307;4937.876553086058;5051.570616497639;5093.312402825719;5139.152039913522;5149.279100925798;5157.922631160461;5114.872128196676;5134.358205224347;5141.897162056813;5169;5188.844334669966;4874.707517548958;4893.388326459192;4896.951030489792;5033.395992480926;5048.192289233705;4860.805567777249;4887.584555312696;4940.7035577865445;4951.282064100757;4981.028699403581;4770.403699537558;4809;4880.341153944172;4890.462049174659;4909.558139767438;4797.82453298944;4860.149473842134;4863.148948933852;4962.082014827269;4965.131102057053;4877.561019508261;4907.60739140875;4911.950880491149;4915.680480768739;4965.900681986268 22:48:37 INFO - PROCESS | 11672 | |1;ext.html;548.9698066606335;550.4990458682598;567.7870798450581;570.3013808085096;574.5719439017932;36171.638283617154;40314;40379.19241615173;40407.595924040754;40475.595244047516;37692.24615507688;38278.617213827856;38297.80851095741;38394.80802595983;38498.80750596243;7779.766607001785;7911.643976021093;7916.762497125095;7927;7944.364450843947;7717.884231736529;7813.101493328283;7867.134615192324;7904;7907.367410607167;7893.171217022216;7926.6829326826855;7943.602819859013;7950;7953.085395179571;7856.646450909723;7864.882026769603;7881.1724768899585;7884.72403465876;7888.7633370998965;7899.170587088388;7915.445918785687;7933.404994625368;7947.6026198690415;7960.601969901481;37553.06117347061;37702;37873.62126378728;37896.62103378958;37945.430818537745;2963.4369469800886;3189.489681650925;3306.8842590509216;3374.8312584370683;3522.823858807075;28598.142055738153;28693.713062869312;29957.700422995928;30167.547486787716;30956.38087238265;3349.1961929137074;3386.881459148918;3428.365752335826;3431.1079119428878;3444.9827750861336;265957.6702116496;266711;267798;267844;268930;54629.1805622916;55230;55267.44732552623;55582;55751;33426.33147337088;33438.498422523684;34037.319253615286;35867.82066089705;36269.81865090658;13480.191188528719;13564.796528052088;13588.79616805729;13602.455901764017;13643.727125457435;10725.14198864089;10768.461576921123;10808.51361688726;10833.349998999927;10878.184136189817;18427.72358414625;18532.907335463507;18624.72062919057;18680.813191867906;18753.156107975174;6579.934200658028;6742.42689371409;6803;6847.246802851682;7061.964690176619;816.7508909782455;885.3846576629181;967.5597603090577;1012.5392946209457;1021.4075836015094;28183.154505364873;28510.004299699238;29218.853905730335;29272.268193294913;29793.851030744707;892.4511425473416;962.9181519570775;1032.9431881246603;1038.444432228754;1044.806710758512;14722.190279534729;14930.701385972437;15237.466688665843;15406.922965385102;15708.371665133267;323646;415620;417856;420582;420883;51823;51980;52033.21950170675;52222.21666675003;52250;1201.9098567607464;1207.390267914715;1220.9450574724158;1224.5163160551533;1229.84012078431;28833;30303.090907272817;30419.39161216719;30423.5591350061;30424.39151216919;44286.778566106965;44309.77845110754;44693;44898.77550612226;45350.54649453463;363680;369242;372743;373686;374774;25222.62166067547;25300.620490692287;25429.61855572131;25688;25724.742752572234;40857;41498;42136.57863421326;42281;42336.78831605822;36309.45535816912;37029.44455833219;37338.25323493461;37360.25279494449;37789.62210377861;36671.81664091662;36959.260814784095;37240;37565.39555608662;38294;35095;35870.10324741902;35977.46033809547;36511.63488365189;36781.44827827638;32275.838620806746;33071.50392744159;33330.33339333248;33691.663083368854;33818.154546136546;25358;25963.740362596887;26101.347466312734;26178.60732088982;26545.33636659099 22:48:37 INFO - PROCESS | 11672 | |2;jquery.html;368.6589904338487;369.532541335211;373.9813009349532;384.13952745849303;386.7312218008485;16046.75929861053;17521.824781752177;18379.081045947714;18673.253069877188;18713.625727485443;16629.83370166298;16956.06741629213;17358.826411735878;17366.305347786107;17748.580113590913;5129.179331306992;5270.525652691257;5271.710055946922;5273.077211487994;5312.150055991042;3815.7138214633874;5173.3533308336455;5827.504662103705;6125.264968203822;6143.815685529441;6087.939120608804;6174.382561743834;6185.536084793636;6190.3500132486115;6235.409970457527;6156.630602163885;6161.260648722159;6169.413905678954;6178.536609754264;6191.288001879797;4700.1539722849875;5020.397552293712;5367.75845086972;5423.864403389907;5437.510624043855;12464.439100240512;12627.558035468715;12706.792854678792;12707.618771436872;18300.90849545757;1907.246637578149;1984.7122167285663;1985.076939223268;2009.65835807913;2349.6475528670667;9104.089591040909;9114.58984345706;9144.588493517807;9291.117343852324;9295.442273463614;1671.1978250439856;1703;1707.2488105233658;1731.480555833245;1761.8150094240048;62990.68504657493;66286.66856665685;66541;67495.32504674989;68341.65829170872;9730.270229732798;9819.36174148678;10023.849642255373;10122.696319110439;10145.746356341004;13144.40850161726;13176.538821141214;13218.339083045808;13231.206127632373;13258.204507729568;6739;6749.628770417674;6816.318368163144;6866.279040700654;6963;6354.983202687547;6410.294867564564;6523.575967562093;6533.705983230766;6535.379138981789;4121.690873184523;4243.511996120424;4284.892877678083;4317.611414972667;4347.0871117065135;1639.9180040997903;1742;1802.2160360243074;1823.2798044772242;1849.3897013985352;787.8660627693306;879.0725784297499;935.5509355509394;959.2901253072815;995.0696099147176;17035.14824258782;17209.483715488554;17261.482155535356;17287.74068388975;17621.38325158639;873.4977388001925;875.3741075131185;892.0143241717938;907.491804589432;912.9224015958717;7125.82185445368;9770.853437198448;9798.26513011527;9847.458389788488;9890.851637225447;378609.21390785737;378762;382786;384419;398182;76217.23782762101;76417.23582764213;79059;79387.60306198432;79860.20139798644;896.7354630384052;909.4452384045727;912.0924679943498;916.5508900638639;918.3938600523622;12039.879601203877;13639.431465381607;15578.298976545855;15796.447124350592;16090.839091608934;36325.818370907975;36433.63566364302;37526.49894004209;38078;38210.80894595509;46322.30516542187;49360.25959610537;50807.74596126996;53692.19461708156;56262;7377.4098072155175;7401.48189626721;7621.237876212334;7641.1212710539;7685.269899359552;8521.616527256165;11547.826782598437;11685.591004314809;11709;12038.03695704324;10184.388936663823;10222.437765922945;10365.74085647865;10390.948045259725;10509.78980420372;9870.309078364733;10187.898121018992;10386.688399348031;10419.270651054656;10512.94743526308;9980.550875210778;10036.598536058476;10228.897711023092;10673.946630266799;10735.516901739597;6577.80266592003;6863;6993.860122797617;7584.924150758422;7704.961475192588;9651.227901767701;10194.259669253832;10371.896281037092;10551.1535481292;10677.786444270916 22:48:37 INFO - PROCESS | 11672 | |3;mootools.html;1373.1006190944931;1409.8237720284965;1479.889008324376;1677.1278934953816;1685.4606525911713;6496.675166241693;7303.01409309743;7356.963215183923;7850.136484986647;7870.724524641639;7275.708971641128;7417.925820741791;7578.128515220751;7606.6957321707205;7707.036620422447;2153.676948457732;2211.7898799614013;2218.678291647718;2224.5217278285168;2227.2538699535676;2200.3838925101;2201.152556265833;2206.680031395447;2232.5423288225957;2233.8101261392803;2008.9899550502228;2012.7383440152832;2075.699023641571;2077.8441616878717;2086.8121869031784;2122.479992401864;2127.2448280860303;2131.1688441507827;2141.8929053547417;2143.5605700831293;2123.5859007493546;2139.871607703543;2143.7427508699056;2146.549224662807;2161.643328850738;7662.1571627120975;7735.342495887856;7738.226177382273;7739.458237923348;7754.457187996843;4580.725156490621;4652.976735116303;4675.953240467586;4685.2972054191805;4692.41344831896;2895.319599894023;2968.4805159097073;2982.3438843454624;2998.595189649413;3001.1446737679876;948.4925564822855;951.3863558005118;970.8203982263304;987.7530617345664;1001.4091685905278;10110.69667909964;10263.846042309371;10309.587616495332;10326.380417174994;10342.069213770797;4059.127287633114;4168.874933751992;4271.700980931367;4311.09467011931;4315.697901146952;8852;8897.955510222408;8930.687425940061;8940;8966.237869781142;4670.6497012724185;4678.883027924329;4683.6721429500285;4684.601808846286;4685.5782979532005;3701.6668499835146;4063.9796801015805;4185.807044992162;4201.7268877522865;4214.452121224222;3591.5690117186105;3609.873654422083;3675.1363429594026;3710.220853620716;3729.0118118698565;1178.6581891251612;1195.635331223985;1199.8260252263374;1203.6449247472017;1204.3015051270243;675.6925598852511;801.3188789528971;865.0700496965787;868.5961028121953;877.4515927545284;4969.378827646544;5133.614978876599;5299.31108955841;5307.734613269321;5351.545118664957;846.2510678049847;857.0058731871;914.4696076275744;929.8930622978309;949.4588084791602;3556.76881002734;3624;3679.816009199529;3828.272628200633;3830.942535861964;9122.087791220825;9170.358074934824;9550.713478595653;9564.56959436827;9598.184154346958;9201.861972070425;9215.49314787693;9223.953880230556;9245.583948722324;9341.439513629204;6828.965855170792;6932.23745388007;6964;6985.3713165815325;7297.4891757577525;5255.842324730264;5268.6575372600655;5281.207818827206;5282.128448805904;5284.735763211747;8699.565021749015;8755.168259015383;8763.211310982042;8839.46963182211;8905.599248033725;8843.380963332505;8912.287017038492;9039.728808135766;9079.636814527345;9095.49974751395;5593.580481463988;6094.664793436226;6261.123442718114;6422.42198202164;6512.185976752906;7343.265673432614;7382.926170738224;7426.777196684108;7439.479236453612;7485.850282994418;6483.319251478527;6524.771632992823;6620.205575330991;6732.451536146616;6825.55633883796;6550.377714117151;6649.767258145942;6716.429103526059;6735.528513004043;6796.898046529405;6118.908216376669;6200.410960958701;6233.812985610439;6238.625682459067;6247.812565623039;4453.777311134366;4617;4620.5379462053525;4735.905281894412;4751.453582838018;4584.770761461847;4673.392458980311;4704.5631583386885;4730.148573256847;4733.479317275028 22:48:37 INFO - PROCESS | 11672 | |4;prototype.html;297.8555400630694;303.1118821851974;306.9002574163397;314.05469536694545;315.6748548994535;4801.90396192076;4895.975520122403;4945.653804233705;5013.79668879469;5016.67058229569;4916.262560615909;4953.826616068438;4955.9840232752285;4964.354633897588;5008.449070602231;3930.6265904739003;3936.6260205280596;3945.487086678728;3953.3279342511696;3956.1296514766705;3663.230721548478;3942.605739426049;3943.2310699413624;3943.7830919299427;3957.663598594123;3755.1599716139167;3833.290841194374;3833.310004199243;3834.731568790186;3842.558105817825;3810.580836108026;3828.7319887607878;3838.539375274957;3841.519810023747;3860.610180335077;3368.8652453901814;3376.3584918865336;3384.813835239061;3385.7291416686608;3390.74569407293;4690.613307627715;4815.085133824562;4858.17411040124;4859.854204373874;4869.883274704192;4381.452318460192;4385.736855788663;4406.9779651101535;4411.602955733967;4415.889602759925;5200.375954885438;5203.7398130093725;5213.800825810047;5219.225463342482;5226.895462090737;3581.391163502211;3585.94621080684;3680.9447858282147;3684.318401095779;3687.483752274684;5452.91820622691;5459.3994660587305;5459.771551400942;5460.706503268597;5466.617336786427;3791.431285307226;3794.392897136445;3799.955012371565;3804.942925856059;3806.1626442182674;3849.9037524062123;3856.267309211241;3858.7684738915755;3859.9807000964815;3874.1670540833857;4307.633851122627;4331.53967357374;4388.188185185751;4402.713823601455;4423.469183697976;5188.740562971836;5222.832524502289;5226.451222621646;5226.817061402832;5227.843164705065;1253.254313683357;1288.62629837348;1299.4282515693064;1302.5831733845268;1303.68711509239;886.577043844633;894.4543828264754;906.9183773460288;909.7816524034184;914.7513643876194;4461.620762235181;4497.707649002804;4501.662375321798;4518.277075667877;4521.756516957863;5271.551918086929;5292.788288468418;5314.627976041717;5339.5728341732565;5344.358676958712;4357.9128417431475;4510.864674059783;4522.547745225517;4532.614727748112;4548.909021819545;2503.561876671575;2527.671402717635;2529.6711427514306;2530.4053547416343;2532.7467253274526;4459.821607135743;4462.326627514682;4487.349334346567;4491.932621010687;4503.527129651405;4596.977015114903;4793.424789025339;4802.6638135330195;4804.807807687724;4819.95180048202;4334.696571239983;4472.753998530047;4481.731096134243;4484.080763443421;4505.707129036602;4709.434867815884;4712.340272361804;4723.31511930775;4740.597049250852;4753.382060332135;4646.514114948486;4647.9767601161775;4654.671909584663;4664.8367307145445;4668.5098064702715;4289.635380992526;4427.317619304644;4433.645308375258;4454.331850222363;4483.237849565647;3424.760266781301;3425.5718035245595;3450.2236996675547;3458.654134586521;3458.948115778215;4675.462321833033;4678.888539121446;4682.1103990241745;4690;5125.718085505333;5085.262636917552;5220.363716323232;5220.530152286313;5221.895562088813;5234.214867769865;5186.17021276609;5189.948100518946;5207.973960130175;5230.293910322058;5252.579793616578;4938.209886418156;5039.370078740158;5073.34046573943;5094.541491265803;5119.92320115206;5217.061118855392;5232.293640358503;5235.60732945023;5259.894802104013;5260.631755777212;5128.435872054146;5128.897422051464;5134.743262836918;5137.6660517066275;5142.202958541432 22:48:37 INFO - PROCESS | 11672 | |5;yui.html;654.3195077119796;656.9770058047969;674.5210900260814;739.3235189801333;762.9313361797438;12365.31990740509;12819.807702884464;12822.807657885127;12833.42249598768;12836.165649232806;12246.387680615955;12489.437975291112;12795.616131516046;12825.551105711302;12958.676033099176;615.1940957345869;757.8067592763838;763.1224092293878;766.4979438467799;767.2212704105337;757.9431542634297;761.2577736706722;761.5887420792793;773.9226077392273;777.9494332868345;814.9185081491863;820.8604537228656;823.086374124721;824.9051359093721;825.4469505431346;801.9799505012393;802.6949759091566;807.8707406814881;808.337163525909;813.686730608717;596.3201949777257;596.5406636889614;599.5892813422819;599.8170557979814;601.1913975702673;12410.875891241063;12455.564055258023;12492.188007779556;12550.058745594026;12582.811257831048;11204.327740335606;11357.091432685447;11662.941685291604;11714.121440891968;11973.102017348734;10391.584336626525;10503.52734126966;10589.523471443803;10645.201609879212;10778.78442431147;3243.189202699325;3307.3054658521737;3334.4664853623303;3343.8160901150427;3415.316936612687;62532.37467625357;63442.365576344106;63649.68175159141;64090.679546602434;65193;10098.141657959155;10211.387316761018;10229.795404091876;10282.691519254435;10445.68662940113;19697.803021969597;20134.69797953032;20164.092615832324;20211.8989405052;20241.291554795807;6814.182298124158;6916.20463646677;7027.402670773042;7054;7225.6387180641805;3779.074126838942;3817.84728610858;3843.7117216208335;3845.5577608575372;3887.688984881203;2871.870765815543;2935.207493976657;2953.822770633769;2965;2976.1964269647074;516.5144763921901;525.9342582177228;528.3026405145248;529.5472371122712;539.5629540072554;532.3212903548007;632.3771085480797;633.297040285283;645.6964332722446;665.8601693644292;8497.447665901696;8733.781655458537;8789;8861.291096712248;8904.55477226149;484.15998243048284;484.4646665434714;593.8716438766293;648.7235991779514;657.9671016449158;4095.4266402703647;4134.64855487281;4166.8333266669;4190.371444283321;4302.741835489881;52926.94146117056;84454.73317900236;94070.5296473527;97036;97084.51457742666;53512;77371.61314193394;78127.61631383865;78517.60741196257;78830;44391.55608443939;52364.73817630888;68855;71764.28235717681;72336.27663723401;7754.263344982218;7994.880076798736;8109.59452027408;8131.105578386491;8204.641876715747;43283.783581081894;43817.99999999873;43990.580518071816;44337.334939975284;45842.312365315214;35762.28475430529;37899.431508527945;39536.40695389629;42638.78680606577;62325;11221.326720396803;11350.602728904449;11629.592964246549;11795;11854.288742675468;12416.379181041093;12505.43725532335;12620.242785432903;12819;13401.66495837612;12265;12407.25556466615;12677.049221308254;12703.809442858179;12807.80788288195;12703.301318427575;12785.424655890693;12810.103292769416;13137.211767293993;13797.310134493035;11872.109591780478;12463.915775173995;12638.304893230592;12858.807117893051;12955.870441295467;10343.84484232722;10495.580176792842;10773.19201059908;11312.6040588579;11318.660440186808;10416;11074.944625276821;11416.372099534605;11537.461758413472;11595.246308989888 22:48:37 INFO - PROCESS | 11672 | __end_tp_report 22:48:37 INFO - PROCESS | 11672 | __start_cc_report 22:48:37 INFO - PROCESS | 11672 | _x_x_mozilla_cycle_collect,389 22:48:37 INFO - PROCESS | 11672 | __end_cc_report 22:48:37 INFO - PROCESS | 11672 | __startTimestamp1468302517660__endTimestamp 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | ------- Summary: start ------- 22:48:37 INFO - PROCESS | 11672 | Number of tests: 6 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | [#0] dojo.html Cycles:165 Average:4701.14 Median:4863.15 stddev:599.03 (12.3%) stddev-sans-first:577.81 22:48:37 INFO - PROCESS | 11672 | Values: 2602.9 2653.2 2664.1 2664.7 2667.2 4691.2 4775.5 4776.1 4868.2 4875.7 4712.2 4797.5 4797.9 4810.9 4812.4 4363.5 4382.4 4410.4 4411.5 4463.4 4381.1 4387.1 4394.5 4461.2 4493.2 539.9 4398.3 4434.3 4443.8 4495.7 4398.8 4400.1 4400.7 4402.6 4464.9 4338.2 4371.8 4390.3 4393.0 4417.5 4730.2 4758.7 4773.2 4780.5 4793.1 5077.0 5103.3 5110.7 5117.7 5139.8 5160.9 5170.7 5174.2 5175.9 5182.3 4663.3 4693.3 4761.8 4775.2 4823.9 5248.7 5270.7 5289.7 5304.2 5319.7 4918.0 5046.6 5078.4 5082.0 5091.2 5063.5 5097.5 5135.6 5146.0 5173.5 5092.0 5109.9 5110.2 5121.5 5167.6 4892.3 4980.2 5050.3 5051.7 5065.3 4145.2 4278.4 4290.1 4306.7 4372.0 3713.3 3772.1 3775.6 3783.2 3821.8 4308.7 4361.5 4415.6 4430.5 4452.7 5081.8 5128.8 5141.4 5181.8 5209.7 4349.0 4356.8 4389.3 4392.5 4406.6 4482.8 4587.8 4595.2 4644.3 4674.2 4803.6 4848.7 4850.9 4872.7 4874.6 5153.6 5158.5 5160.4 5197.3 5214.9 4693.6 4825.5 4878.3 4892.3 4937.9 5051.6 5093.3 5139.2 5149.3 5157.9 5114.9 5134.4 5141.9 5169.0 5188.8 4874.7 4893.4 4897.0 5033.4 5048.2 4860.8 4887.6 4940.7 4951.3 4981.0 4770.4 4809.0 4880.3 4890.5 4909.6 4797.8 4860.1 4863.1 4962.1 4965.1 4877.6 4907.6 4912.0 4915.7 4965.9 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | [#1] ext.html Cycles:180 Average:49471.38 Median:203100.63 stddev:92715.84 (45.7%) stddev-sans-first:92903.17 22:48:37 INFO - PROCESS | 11672 | Values: 549.0 550.5 567.8 570.3 574.6 36171.6 40314.0 40379.2 40407.6 40475.6 37692.2 38278.6 38297.8 38394.8 38498.8 7779.8 7911.6 7916.8 7927.0 7944.4 7717.9 7813.1 7867.1 7904.0 7907.4 7893.2 7926.7 7943.6 7950.0 7953.1 7856.6 7864.9 7881.2 7884.7 7888.8 7899.2 7915.4 7933.4 7947.6 7960.6 37553.1 37702.0 37873.6 37896.6 37945.4 2963.4 3189.5 3306.9 3374.8 3522.8 28598.1 28693.7 29957.7 30167.5 30956.4 3349.2 3386.9 3428.4 3431.1 3445.0 265957.7 266711.0 267798.0 267844.0 268930.0 54629.2 55230.0 55267.4 55582.0 55751.0 33426.3 33438.5 34037.3 35867.8 36269.8 13480.2 13564.8 13588.8 13602.5 13643.7 10725.1 10768.5 10808.5 10833.3 10878.2 18427.7 18532.9 18624.7 18680.8 18753.2 6579.9 6742.4 6803.0 6847.2 7062.0 816.8 885.4 967.6 1012.5 1021.4 28183.2 28510.0 29218.9 29272.3 29793.9 892.5 962.9 1032.9 1038.4 1044.8 14722.2 14930.7 15237.5 15406.9 15708.4 323646.0 415620.0 417856.0 420582.0 420883.0 51823.0 51980.0 52033.2 52222.2 52250.0 1201.9 1207.4 1220.9 1224.5 1229.8 28833.0 30303.1 30419.4 30423.6 30424.4 44286.8 44309.8 44693.0 44898.8 45350.5 363680.0 369242.0 372743.0 373686.0 374774.0 25222.6 25300.6 25429.6 25688.0 25724.7 40857.0 41498.0 42136.6 42281.0 42336.8 36309.5 37029.4 37338.3 37360.3 37789.6 36671.8 36959.3 37240.0 37565.4 38294.0 35095.0 35870.1 35977.5 36511.6 36781.4 32275.8 33071.5 33330.3 33691.7 33818.2 25358.0 25963.7 26101.3 26178.6 26545.3 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | [#2] jquery.html Cycles:180 Average:23930.26 Median:25511.23 stddev:63549.81 (249.1%) stddev-sans-first:63703.46 22:48:37 INFO - PROCESS | 11672 | Values: 368.7 369.5 374.0 384.1 386.7 16046.8 17521.8 18379.1 18673.3 18713.6 16629.8 16956.1 17358.8 17366.3 17748.6 5129.2 5270.5 5271.7 5273.1 5312.2 3815.7 5173.4 5827.5 6125.3 6143.8 6087.9 6174.4 6185.5 6190.4 6235.4 6156.6 6161.3 6169.4 6178.5 6191.3 4700.2 5020.4 5367.8 5423.9 5437.5 12464.4 12627.6 12706.8 12707.6 18300.9 1907.2 1984.7 1985.1 2009.7 2349.6 9104.1 9114.6 9144.6 9291.1 9295.4 1671.2 1703.0 1707.2 1731.5 1761.8 62990.7 66286.7 66541.0 67495.3 68341.7 9730.3 9819.4 10023.8 10122.7 10145.7 13144.4 13176.5 13218.3 13231.2 13258.2 6739.0 6749.6 6816.3 6866.3 6963.0 6355.0 6410.3 6523.6 6533.7 6535.4 4121.7 4243.5 4284.9 4317.6 4347.1 1639.9 1742.0 1802.2 1823.3 1849.4 787.9 879.1 935.6 959.3 995.1 17035.1 17209.5 17261.5 17287.7 17621.4 873.5 875.4 892.0 907.5 912.9 7125.8 9770.9 9798.3 9847.5 9890.9 378609.2 378762.0 382786.0 384419.0 398182.0 76217.2 76417.2 79059.0 79387.6 79860.2 896.7 909.4 912.1 916.6 918.4 12039.9 13639.4 15578.3 15796.4 16090.8 36325.8 36433.6 37526.5 38078.0 38210.8 46322.3 49360.3 50807.7 53692.2 56262.0 7377.4 7401.5 7621.2 7641.1 7685.3 8521.6 11547.8 11685.6 11709.0 12038.0 10184.4 10222.4 10365.7 10390.9 10509.8 9870.3 10187.9 10386.7 10419.3 10512.9 9980.6 10036.6 10228.9 10673.9 10735.5 6577.8 6863.0 6993.9 7584.9 7705.0 9651.2 10194.3 10371.9 10551.2 10677.8 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | [#3] mootools.html Cycles:180 Average:5064.21 Median:5281.67 stddev:2778.06 (52.6%) stddev-sans-first:2772.00 22:48:37 INFO - PROCESS | 11672 | Values: 1373.1 1409.8 1479.9 1677.1 1685.5 6496.7 7303.0 7357.0 7850.1 7870.7 7275.7 7417.9 7578.1 7606.7 7707.0 2153.7 2211.8 2218.7 2224.5 2227.3 2200.4 2201.2 2206.7 2232.5 2233.8 2009.0 2012.7 2075.7 2077.8 2086.8 2122.5 2127.2 2131.2 2141.9 2143.6 2123.6 2139.9 2143.7 2146.5 2161.6 7662.2 7735.3 7738.2 7739.5 7754.5 4580.7 4653.0 4676.0 4685.3 4692.4 2895.3 2968.5 2982.3 2998.6 3001.1 948.5 951.4 970.8 987.8 1001.4 10110.7 10263.8 10309.6 10326.4 10342.1 4059.1 4168.9 4271.7 4311.1 4315.7 8852.0 8898.0 8930.7 8940.0 8966.2 4670.6 4678.9 4683.7 4684.6 4685.6 3701.7 4064.0 4185.8 4201.7 4214.5 3591.6 3609.9 3675.1 3710.2 3729.0 1178.7 1195.6 1199.8 1203.6 1204.3 675.7 801.3 865.1 868.6 877.5 4969.4 5133.6 5299.3 5307.7 5351.5 846.3 857.0 914.5 929.9 949.5 3556.8 3624.0 3679.8 3828.3 3830.9 9122.1 9170.4 9550.7 9564.6 9598.2 9201.9 9215.5 9224.0 9245.6 9341.4 6829.0 6932.2 6964.0 6985.4 7297.5 5255.8 5268.7 5281.2 5282.1 5284.7 8699.6 8755.2 8763.2 8839.5 8905.6 8843.4 8912.3 9039.7 9079.6 9095.5 5593.6 6094.7 6261.1 6422.4 6512.2 7343.3 7382.9 7426.8 7439.5 7485.9 6483.3 6524.8 6620.2 6732.5 6825.6 6550.4 6649.8 6716.4 6735.5 6796.9 6118.9 6200.4 6233.8 6238.6 6247.8 4453.8 4617.0 4620.5 4735.9 4751.5 4584.8 4673.4 4704.6 4730.1 4733.5 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | [#4] prototype.html Cycles:180 Average:4178.80 Median:4520.02 stddev:1208.75 (26.7%) stddev-sans-first:1176.52 22:48:37 INFO - PROCESS | 11672 | Values: 297.9 303.1 306.9 314.1 315.7 4801.9 4896.0 4945.7 5013.8 5016.7 4916.3 4953.8 4956.0 4964.4 5008.4 3930.6 3936.6 3945.5 3953.3 3956.1 3663.2 3942.6 3943.2 3943.8 3957.7 3755.2 3833.3 3833.3 3834.7 3842.6 3810.6 3828.7 3838.5 3841.5 3860.6 3368.9 3376.4 3384.8 3385.7 3390.7 4690.6 4815.1 4858.2 4859.9 4869.9 4381.5 4385.7 4407.0 4411.6 4415.9 5200.4 5203.7 5213.8 5219.2 5226.9 3581.4 3585.9 3680.9 3684.3 3687.5 5452.9 5459.4 5459.8 5460.7 5466.6 3791.4 3794.4 3800.0 3804.9 3806.2 3849.9 3856.3 3858.8 3860.0 3874.2 4307.6 4331.5 4388.2 4402.7 4423.5 5188.7 5222.8 5226.5 5226.8 5227.8 1253.3 1288.6 1299.4 1302.6 1303.7 886.6 894.5 906.9 909.8 914.8 4461.6 4497.7 4501.7 4518.3 4521.8 5271.6 5292.8 5314.6 5339.6 5344.4 4357.9 4510.9 4522.5 4532.6 4548.9 2503.6 2527.7 2529.7 2530.4 2532.7 4459.8 4462.3 4487.3 4491.9 4503.5 4597.0 4793.4 4802.7 4804.8 4820.0 4334.7 4472.8 4481.7 4484.1 4505.7 4709.4 4712.3 4723.3 4740.6 4753.4 4646.5 4648.0 4654.7 4664.8 4668.5 4289.6 4427.3 4433.6 4454.3 4483.2 3424.8 3425.6 3450.2 3458.7 3458.9 4675.5 4678.9 4682.1 4690.0 5125.7 5085.3 5220.4 5220.5 5221.9 5234.2 5186.2 5189.9 5208.0 5230.3 5252.6 4938.2 5039.4 5073.3 5094.5 5119.9 5217.1 5232.3 5235.6 5259.9 5260.6 5128.4 5128.9 5134.7 5137.7 5142.2 22:48:37 INFO - PROCESS | 11672 | 22:48:37 INFO - PROCESS | 11672 | [#5] yui.html Cycles:180 Average:16422.02 Median:4178.60 stddev:22286.64 (533.4%) stddev-sans-first:22317.71 22:48:37 INFO - PROCESS | 11672 | Values: 654.3 657.0 674.5 739.3 762.9 12365.3 12819.8 12822.8 12833.4 12836.2 12246.4 12489.4 12795.6 12825.6 12958.7 615.2 757.8 763.1 766.5 767.2 757.9 761.3 761.6 773.9 777.9 814.9 820.9 823.1 824.9 825.4 802.0 802.7 807.9 808.3 813.7 596.3 596.5 599.6 599.8 601.2 12410.9 12455.6 12492.2 12550.1 12582.8 11204.3 11357.1 11662.9 11714.1 11973.1 10391.6 10503.5 10589.5 10645.2 10778.8 3243.2 3307.3 3334.5 3343.8 3415.3 62532.4 63442.4 63649.7 64090.7 65193.0 10098.1 10211.4 10229.8 10282.7 10445.7 19697.8 20134.7 20164.1 20211.9 20241.3 6814.2 6916.2 7027.4 7054.0 7225.6 3779.1 3817.8 3843.7 3845.6 3887.7 2871.9 2935.2 2953.8 2965.0 2976.2 516.5 525.9 528.3 529.5 539.6 532.3 632.4 633.3 645.7 665.9 8497.4 8733.8 8789.0 8861.3 8904.6 484.2 484.5 593.9 648.7 658.0 4095.4 4134.6 4166.8 4190.4 4302.7 52926.9 84454.7 94070.5 97036.0 97084.5 53512.0 77371.6 78127.6 78517.6 78830.0 44391.6 52364.7 68855.0 71764.3 72336.3 7754.3 7994.9 8109.6 8131.1 8204.6 43283.8 43818.0 43990.6 44337.3 45842.3 35762.3 37899.4 39536.4 42638.8 62325.0 11221.3 11350.6 11629.6 11795.0 11854.3 12416.4 12505.4 12620.2 12819.0 13401.7 12265.0 12407.3 12677.0 12703.8 12807.8 12703.3 12785.4 12810.1 13137.2 13797.3 11872.1 12463.9 12638.3 12858.8 12955.9 10343.8 10495.6 10773.2 11312.6 11318.7 10416.0 11074.9 11416.4 11537.5 11595.2 22:48:37 INFO - PROCESS | 11672 | -------- Summary: end -------- 22:48:37 INFO - PROCESS | 11672 | 22:48:38 INFO - TEST-INFO | 11672: exit 0 22:48:38 INFO - TEST-OK | dromaeo_css | took 1116427ms 22:48:38 INFO - TEST-START | kraken 22:48:38 INFO - operating with platform_type : linux_ 22:48:38 INFO - Initialising browser for kraken test... 22:48:38 INFO - TEST-INFO | started process 11915 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCQ5qYq/profile http://localhost:59225/getInfo.html) 22:48:43 INFO - PROCESS | 11915 | __metrics Screen width/height:1600/1200 22:48:43 INFO - PROCESS | 11915 | colorDepth:24 22:48:43 INFO - PROCESS | 11915 | Browser inner width/height: 1024/697 22:48:43 INFO - PROCESS | 11915 | __metrics 22:48:44 ERROR - PROCESS | 11915 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 22:48:44 INFO - TEST-INFO | 11915: exit 0 22:48:44 INFO - Browser initialized. 22:48:44 INFO - Running cycle 1/1 for kraken test... 22:48:44 INFO - TEST-INFO | started process 12033 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCQ5qYq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:48:57 INFO - PROCESS | 12033 | RSS: Main: 168521728 22:48:57 INFO - PROCESS | 12033 | 22:48:59 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59225/tests/kraken/driver.html?testName=audio-beat-detection) 22:49:00 INFO - PROCESS | 12033 | RSS: Main: 173809664 22:49:00 INFO - PROCESS | 12033 | 22:49:03 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59225/tests/kraken/driver.html?testName=audio-dft) 22:49:04 INFO - PROCESS | 12033 | RSS: Main: 173494272 22:49:04 INFO - PROCESS | 12033 | 22:49:06 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59225/tests/kraken/driver.html?testName=audio-fft) 22:49:07 INFO - PROCESS | 12033 | RSS: Main: 168112128 22:49:07 INFO - PROCESS | 12033 | 22:49:09 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59225/tests/kraken/driver.html?testName=audio-oscillator) 22:49:10 INFO - PROCESS | 12033 | RSS: Main: 168972288 22:49:10 INFO - PROCESS | 12033 | 22:49:12 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:49:13 INFO - PROCESS | 12033 | RSS: Main: 168828928 22:49:13 INFO - PROCESS | 12033 | 22:49:16 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59225/tests/kraken/driver.html?testName=imaging-darkroom) 22:49:17 INFO - PROCESS | 12033 | RSS: Main: 169390080 22:49:17 INFO - PROCESS | 12033 | 22:49:20 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59225/tests/kraken/driver.html?testName=imaging-desaturate) 22:49:21 INFO - PROCESS | 12033 | RSS: Main: 166817792 22:49:21 INFO - PROCESS | 12033 | 22:49:24 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59225/tests/kraken/driver.html?testName=json-parse-financial) 22:49:25 INFO - PROCESS | 12033 | RSS: Main: 168259584 22:49:25 INFO - PROCESS | 12033 | 22:49:26 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:49:27 INFO - PROCESS | 12033 | RSS: Main: 168996864 22:49:27 INFO - PROCESS | 12033 | 22:49:29 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:49:29 INFO - PROCESS | 12033 | RSS: Main: 169111552 22:49:29 INFO - PROCESS | 12033 | 22:49:32 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:49:32 INFO - PROCESS | 12033 | RSS: Main: 169623552 22:49:32 INFO - PROCESS | 12033 | 22:49:35 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:49:36 INFO - PROCESS | 12033 | RSS: Main: 169832448 22:49:36 INFO - PROCESS | 12033 | 22:49:39 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:49:39 INFO - PROCESS | 12033 | RSS: Main: 169132032 22:49:39 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | Cycle 1(1): loaded http://localhost:59225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59225/tests/kraken/driver.html?testName=ai-astar) 22:49:41 INFO - PROCESS | 12033 | RSS: Main: 170328064 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | __start_tp_report 22:49:41 INFO - PROCESS | 12033 | _x_x_mozilla_page_load 22:49:41 INFO - PROCESS | 12033 | _x_x_mozilla_page_load_details 22:49:41 INFO - PROCESS | 12033 | |i|pagename|runs| 22:49:41 INFO - PROCESS | 12033 | |0;ai-astar;107;113;110;117;114;116;115;116;115;115 22:49:41 INFO - PROCESS | 12033 | |1;audio-beat-detection;156;123;147;177;128;154;157;126;197;125 22:49:41 INFO - PROCESS | 12033 | |2;audio-dft;163;164;165;171;167;165;165;165;163;163 22:49:41 INFO - PROCESS | 12033 | |3;audio-fft;80;79;76;165;79;76;77;77;163;78 22:49:41 INFO - PROCESS | 12033 | |4;audio-oscillator;86;87;145;86;89;88;140;88;87;141 22:49:41 INFO - PROCESS | 12033 | |5;imaging-gaussian-blur;118;119;119;119;119;118;119;119;119;118 22:49:41 INFO - PROCESS | 12033 | |6;imaging-darkroom;149;151;151;149;149;149;149;149;149;150 22:49:41 INFO - PROCESS | 12033 | |7;imaging-desaturate;96;97;96;96;96;97;95;96;95;96 22:49:41 INFO - PROCESS | 12033 | |8;json-parse-financial;64;63;66;64;66;65;65;65;67;63 22:49:41 INFO - PROCESS | 12033 | |9;json-stringify-tinderbox;67;67;66;66;66;66;66;66;66;65 22:49:41 INFO - PROCESS | 12033 | |10;stanford-crypto-aes;82;89;84;87;83;79;84;82;92;81 22:49:41 INFO - PROCESS | 12033 | |11;stanford-crypto-ccm;130;131;133;138;138;131;131;133;182;143 22:49:41 INFO - PROCESS | 12033 | |12;stanford-crypto-pbkdf2;173;173;171;177;173;177;175;180;172;171 22:49:41 INFO - PROCESS | 12033 | |13;stanford-crypto-sha256-iterative;77;72;69;68;69;69;68;68;68;76 22:49:41 INFO - PROCESS | 12033 | __end_tp_report 22:49:41 INFO - PROCESS | 12033 | __start_cc_report 22:49:41 INFO - PROCESS | 12033 | _x_x_mozilla_cycle_collect,988 22:49:41 INFO - PROCESS | 12033 | __end_cc_report 22:49:41 INFO - PROCESS | 12033 | __startTimestamp1468302581885__endTimestamp 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | ------- Summary: start ------- 22:49:41 INFO - PROCESS | 12033 | Number of tests: 14 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.08 (2.7%) stddev-sans-first:2.07 22:49:41 INFO - PROCESS | 12033 | Values: 107.0 113.0 110.0 117.0 114.0 116.0 115.0 116.0 115.0 115.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#1] audio-beat-detection Cycles:10 Average:149.00 Median:155.00 stddev:24.61 (15.9%) stddev-sans-first:25.97 22:49:41 INFO - PROCESS | 12033 | Values: 156.0 123.0 147.0 177.0 128.0 154.0 157.0 126.0 197.0 125.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#2] audio-dft Cycles:10 Average:165.10 Median:165.00 stddev:2.42 (1.5%) stddev-sans-first:2.45 22:49:41 INFO - PROCESS | 12033 | Values: 163.0 164.0 165.0 171.0 167.0 165.0 165.0 165.0 163.0 163.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#3] audio-fft Cycles:10 Average:95.00 Median:77.50 stddev:36.39 (47.0%) stddev-sans-first:38.19 22:49:41 INFO - PROCESS | 12033 | Values: 80.0 79.0 76.0 165.0 79.0 76.0 77.0 77.0 163.0 78.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#4] audio-oscillator Cycles:10 Average:103.70 Median:87.00 stddev:26.47 (30.4%) stddev-sans-first:27.29 22:49:41 INFO - PROCESS | 12033 | Values: 86.0 87.0 145.0 86.0 89.0 88.0 140.0 88.0 87.0 141.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#5] imaging-gaussian-blur Cycles:10 Average:118.70 Median:119.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 22:49:41 INFO - PROCESS | 12033 | Values: 118.0 119.0 119.0 119.0 119.0 118.0 119.0 119.0 119.0 118.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:149.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 22:49:41 INFO - PROCESS | 12033 | Values: 149.0 151.0 151.0 149.0 149.0 149.0 149.0 149.0 149.0 150.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 22:49:41 INFO - PROCESS | 12033 | Values: 96.0 97.0 96.0 96.0 96.0 97.0 95.0 96.0 95.0 96.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:65.00 stddev:1.32 (2.0%) stddev-sans-first:1.36 22:49:41 INFO - PROCESS | 12033 | Values: 64.0 63.0 66.0 64.0 66.0 65.0 65.0 65.0 67.0 63.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 22:49:41 INFO - PROCESS | 12033 | Values: 67.0 67.0 66.0 66.0 66.0 66.0 66.0 66.0 66.0 65.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.00 stddev:3.95 (4.7%) stddev-sans-first:4.10 22:49:41 INFO - PROCESS | 12033 | Values: 82.0 89.0 84.0 87.0 83.0 79.0 84.0 82.0 92.0 81.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:135.50 stddev:15.68 (11.6%) stddev-sans-first:16.29 22:49:41 INFO - PROCESS | 12033 | Values: 130.0 131.0 133.0 138.0 138.0 131.0 131.0 133.0 182.0 143.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:174.00 stddev:2.97 (1.7%) stddev-sans-first:3.12 22:49:41 INFO - PROCESS | 12033 | Values: 173.0 173.0 171.0 177.0 173.0 177.0 175.0 180.0 172.0 171.0 22:49:41 INFO - PROCESS | 12033 | 22:49:41 INFO - PROCESS | 12033 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:69.00 stddev:3.44 (5.0%) stddev-sans-first:2.69 22:49:41 INFO - PROCESS | 12033 | Values: 77.0 72.0 69.0 68.0 69.0 69.0 68.0 68.0 68.0 76.0 22:49:41 INFO - PROCESS | 12033 | -------- Summary: end -------- 22:49:41 INFO - PROCESS | 12033 | 22:49:42 INFO - TEST-INFO | 12033: exit 0 22:49:42 INFO - TEST-OK | kraken | took 64512ms 22:49:42 INFO - SUITE-END | took 1180s 22:49:43 INFO - Completed test suite (00:19:40) 22:49:43 INFO - javascript benchmark 22:49:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6391.379540812777, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.895884164633, 2653.1642532602227, 2664.09420796929, 2664.7335266473356, 2667.186508115024, 4691.179043667357, 4775.49857264987, 4776.116418462587, 4868.245421959602, 4875.683080599759, 4712.222483290253, 4797.4962628923895, 4797.856064318067, 4810.927836082453, 4812.374391329122, 4363.52001279859, 4382.430284063067, 4410.404595379631, 4411.514733379326, 4463.4420697412825, 4381.058072514409, 4387.1006443679125, 4394.538573449774, 4461.1523810476065, 4493.168763778695, 539.8991303468498, 4398.318260669569, 4434.290513517838, 4443.800028998703, 4495.730256184639, 4398.7580683062415, 4400.09797991411, 4400.713953593006, 4402.603765661106, 4464.933026004613, 4338.240807858644, 4371.803268852877, 4390.27560452525, 4393.0, 4417.4919884213405, 4730.219513780222, 4758.689846208838, 4773.212419950704, 4780.4741478437345, 4793.089313030548, 5077.035363281002, 5103.336566246401, 5110.693358398508, 5117.744112794346, 5139.794408223666, 5160.896782064376, 5170.689758614495, 5174.197999310113, 5175.948240517584, 5182.274481572583, 4663.27719203524, 4693.272542755844, 4761.761911904391, 4775.2359622460235, 4823.927641085386, 5248.685078895279, 5270.710110943858, 5289.735513224324, 5304.173202385888, 5319.707416092152, 4917.950820491821, 5046.646734728533, 5078.390593128848, 5082.034413461431, 5091.2363145527725, 5063.468335824759, 5097.515736005148, 5135.589152867761, 5145.974270128625, 5173.508516690908, 5092.007058623607, 5109.923351149661, 5110.233464980282, 5121.513456221653, 5167.612429067835, 4892.2661600760175, 4980.153373926441, 5050.3434553507805, 5051.671641343374, 5065.290859279704, 4145.2124096421585, 4278.443802305743, 4290.077633308853, 4306.65546756265, 4372.0, 3713.2573485303046, 3772.0946972726347, 3775.565809931893, 3783.2244389900006, 3821.808909554511, 4308.74147551148, 4361.542038086019, 4415.602595766396, 4430.468343798765, 4452.666050046259, 5081.771320290596, 5128.820491282787, 5141.357330333708, 5181.844544663591, 5209.71346575942, 4348.978255108705, 4356.825726970886, 4389.341598760211, 4392.5168231495145, 4406.581374769392, 4482.7758612069265, 4587.77061146948, 4595.172868883633, 4644.349791029259, 4674.228752255908, 4803.639727020487, 4848.660593758403, 4850.927236091391, 4872.658913875995, 4874.5856602189215, 5153.639245252796, 5158.484151584811, 5160.432352441304, 5197.298364720715, 5214.9478505214465, 4693.577578017995, 4825.541573550507, 4878.341423907727, 4892.315075889307, 4937.876553086058, 5051.570616497639, 5093.312402825719, 5139.152039913522, 5149.279100925798, 5157.922631160461, 5114.872128196676, 5134.358205224347, 5141.897162056813, 5169.0, 5188.844334669966, 4874.707517548958, 4893.388326459192, 4896.951030489792, 5033.395992480926, 5048.192289233705, 4860.805567777249, 4887.584555312696, 4940.7035577865445, 4951.282064100757, 4981.028699403581, 4770.403699537558, 4809.0, 4880.341153944172, 4890.462049174659, 4909.558139767438, 4797.82453298944, 4860.149473842134, 4863.148948933852, 4962.082014827269, 4965.131102057053, 4877.561019508261, 4907.60739140875, 4911.950880491149, 4915.680480768739, 4965.900681986268], "value": 4664.757650204131, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.9698066606335, 550.4990458682598, 567.7870798450581, 570.3013808085096, 574.5719439017932, 36171.638283617154, 40314.0, 40379.19241615173, 40407.595924040754, 40475.595244047516, 37692.24615507688, 38278.617213827856, 38297.80851095741, 38394.80802595983, 38498.80750596243, 7779.766607001785, 7911.643976021093, 7916.762497125095, 7927.0, 7944.364450843947, 7717.884231736529, 7813.101493328283, 7867.134615192324, 7904.0, 7907.367410607167, 7893.171217022216, 7926.6829326826855, 7943.602819859013, 7950.0, 7953.085395179571, 7856.646450909723, 7864.882026769603, 7881.1724768899585, 7884.72403465876, 7888.7633370998965, 7899.170587088388, 7915.445918785687, 7933.404994625368, 7947.6026198690415, 7960.601969901481, 37553.06117347061, 37702.0, 37873.62126378728, 37896.62103378958, 37945.430818537745, 2963.4369469800886, 3189.489681650925, 3306.8842590509216, 3374.8312584370683, 3522.823858807075, 28598.142055738153, 28693.713062869312, 29957.700422995928, 30167.547486787716, 30956.38087238265, 3349.1961929137074, 3386.881459148918, 3428.365752335826, 3431.1079119428878, 3444.9827750861336, 265957.6702116496, 266711.0, 267798.0, 267844.0, 268930.0, 54629.1805622916, 55230.0, 55267.44732552623, 55582.0, 55751.0, 33426.33147337088, 33438.498422523684, 34037.319253615286, 35867.82066089705, 36269.81865090658, 13480.191188528719, 13564.796528052088, 13588.79616805729, 13602.455901764017, 13643.727125457435, 10725.14198864089, 10768.461576921123, 10808.51361688726, 10833.349998999927, 10878.184136189817, 18427.72358414625, 18532.907335463507, 18624.72062919057, 18680.813191867906, 18753.156107975174, 6579.934200658028, 6742.42689371409, 6803.0, 6847.246802851682, 7061.964690176619, 816.7508909782455, 885.3846576629181, 967.5597603090577, 1012.5392946209457, 1021.4075836015094, 28183.154505364873, 28510.004299699238, 29218.853905730335, 29272.268193294913, 29793.851030744707, 892.4511425473416, 962.9181519570775, 1032.9431881246603, 1038.444432228754, 1044.806710758512, 14722.190279534729, 14930.701385972437, 15237.466688665843, 15406.922965385102, 15708.371665133267, 323646.0, 415620.0, 417856.0, 420582.0, 420883.0, 51823.0, 51980.0, 52033.21950170675, 52222.21666675003, 52250.0, 1201.9098567607464, 1207.390267914715, 1220.9450574724158, 1224.5163160551533, 1229.84012078431, 28833.0, 30303.090907272817, 30419.39161216719, 30423.5591350061, 30424.39151216919, 44286.778566106965, 44309.77845110754, 44693.0, 44898.77550612226, 45350.54649453463, 363680.0, 369242.0, 372743.0, 373686.0, 374774.0, 25222.62166067547, 25300.620490692287, 25429.61855572131, 25688.0, 25724.742752572234, 40857.0, 41498.0, 42136.57863421326, 42281.0, 42336.78831605822, 36309.45535816912, 37029.44455833219, 37338.25323493461, 37360.25279494449, 37789.62210377861, 36671.81664091662, 36959.260814784095, 37240.0, 37565.39555608662, 38294.0, 35095.0, 35870.10324741902, 35977.46033809547, 36511.63488365189, 36781.44827827638, 32275.838620806746, 33071.50392744159, 33330.33339333248, 33691.663083368854, 33818.154546136546, 25358.0, 25963.740362596887, 26101.347466312734, 26178.60732088982, 26545.33636659099], "value": 17640.064174938827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.6589904338487, 369.532541335211, 373.9813009349532, 384.13952745849303, 386.7312218008485, 16046.75929861053, 17521.824781752177, 18379.081045947714, 18673.253069877188, 18713.625727485443, 16629.83370166298, 16956.06741629213, 17358.826411735878, 17366.305347786107, 17748.580113590913, 5129.179331306992, 5270.525652691257, 5271.710055946922, 5273.077211487994, 5312.150055991042, 3815.7138214633874, 5173.3533308336455, 5827.504662103705, 6125.264968203822, 6143.815685529441, 6087.939120608804, 6174.382561743834, 6185.536084793636, 6190.3500132486115, 6235.409970457527, 6156.630602163885, 6161.260648722159, 6169.413905678954, 6178.536609754264, 6191.288001879797, 4700.1539722849875, 5020.397552293712, 5367.75845086972, 5423.864403389907, 5437.510624043855, 12464.439100240512, 12627.558035468715, 12706.792854678792, 12707.618771436872, 18300.90849545757, 1907.246637578149, 1984.7122167285663, 1985.076939223268, 2009.65835807913, 2349.6475528670667, 9104.089591040909, 9114.58984345706, 9144.588493517807, 9291.117343852324, 9295.442273463614, 1671.1978250439856, 1703.0, 1707.2488105233658, 1731.480555833245, 1761.8150094240048, 62990.68504657493, 66286.66856665685, 66541.0, 67495.32504674989, 68341.65829170872, 9730.270229732798, 9819.36174148678, 10023.849642255373, 10122.696319110439, 10145.746356341004, 13144.40850161726, 13176.538821141214, 13218.339083045808, 13231.206127632373, 13258.204507729568, 6739.0, 6749.628770417674, 6816.318368163144, 6866.279040700654, 6963.0, 6354.983202687547, 6410.294867564564, 6523.575967562093, 6533.705983230766, 6535.379138981789, 4121.690873184523, 4243.511996120424, 4284.892877678083, 4317.611414972667, 4347.0871117065135, 1639.9180040997903, 1742.0, 1802.2160360243074, 1823.2798044772242, 1849.3897013985352, 787.8660627693306, 879.0725784297499, 935.5509355509394, 959.2901253072815, 995.0696099147176, 17035.14824258782, 17209.483715488554, 17261.482155535356, 17287.74068388975, 17621.38325158639, 873.4977388001925, 875.3741075131185, 892.0143241717938, 907.491804589432, 912.9224015958717, 7125.82185445368, 9770.853437198448, 9798.26513011527, 9847.458389788488, 9890.851637225447, 378609.21390785737, 378762.0, 382786.0, 384419.0, 398182.0, 76217.23782762101, 76417.23582764213, 79059.0, 79387.60306198432, 79860.20139798644, 896.7354630384052, 909.4452384045727, 912.0924679943498, 916.5508900638639, 918.3938600523622, 12039.879601203877, 13639.431465381607, 15578.298976545855, 15796.447124350592, 16090.839091608934, 36325.818370907975, 36433.63566364302, 37526.49894004209, 38078.0, 38210.80894595509, 46322.30516542187, 49360.25959610537, 50807.74596126996, 53692.19461708156, 56262.0, 7377.4098072155175, 7401.48189626721, 7621.237876212334, 7641.1212710539, 7685.269899359552, 8521.616527256165, 11547.826782598437, 11685.591004314809, 11709.0, 12038.03695704324, 10184.388936663823, 10222.437765922945, 10365.74085647865, 10390.948045259725, 10509.78980420372, 9870.309078364733, 10187.898121018992, 10386.688399348031, 10419.270651054656, 10512.94743526308, 9980.550875210778, 10036.598536058476, 10228.897711023092, 10673.946630266799, 10735.516901739597, 6577.80266592003, 6863.0, 6993.860122797617, 7584.924150758422, 7704.961475192588, 9651.227901767701, 10194.259669253832, 10371.896281037092, 10551.1535481292, 10677.786444270916], "value": 8127.402716078866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.1006190944931, 1409.8237720284965, 1479.889008324376, 1677.1278934953816, 1685.4606525911713, 6496.675166241693, 7303.01409309743, 7356.963215183923, 7850.136484986647, 7870.724524641639, 7275.708971641128, 7417.925820741791, 7578.128515220751, 7606.6957321707205, 7707.036620422447, 2153.676948457732, 2211.7898799614013, 2218.678291647718, 2224.5217278285168, 2227.2538699535676, 2200.3838925101, 2201.152556265833, 2206.680031395447, 2232.5423288225957, 2233.8101261392803, 2008.9899550502228, 2012.7383440152832, 2075.699023641571, 2077.8441616878717, 2086.8121869031784, 2122.479992401864, 2127.2448280860303, 2131.1688441507827, 2141.8929053547417, 2143.5605700831293, 2123.5859007493546, 2139.871607703543, 2143.7427508699056, 2146.549224662807, 2161.643328850738, 7662.1571627120975, 7735.342495887856, 7738.226177382273, 7739.458237923348, 7754.457187996843, 4580.725156490621, 4652.976735116303, 4675.953240467586, 4685.2972054191805, 4692.41344831896, 2895.319599894023, 2968.4805159097073, 2982.3438843454624, 2998.595189649413, 3001.1446737679876, 948.4925564822855, 951.3863558005118, 970.8203982263304, 987.7530617345664, 1001.4091685905278, 10110.69667909964, 10263.846042309371, 10309.587616495332, 10326.380417174994, 10342.069213770797, 4059.127287633114, 4168.874933751992, 4271.700980931367, 4311.09467011931, 4315.697901146952, 8852.0, 8897.955510222408, 8930.687425940061, 8940.0, 8966.237869781142, 4670.6497012724185, 4678.883027924329, 4683.6721429500285, 4684.601808846286, 4685.5782979532005, 3701.6668499835146, 4063.9796801015805, 4185.807044992162, 4201.7268877522865, 4214.452121224222, 3591.5690117186105, 3609.873654422083, 3675.1363429594026, 3710.220853620716, 3729.0118118698565, 1178.6581891251612, 1195.635331223985, 1199.8260252263374, 1203.6449247472017, 1204.3015051270243, 675.6925598852511, 801.3188789528971, 865.0700496965787, 868.5961028121953, 877.4515927545284, 4969.378827646544, 5133.614978876599, 5299.31108955841, 5307.734613269321, 5351.545118664957, 846.2510678049847, 857.0058731871, 914.4696076275744, 929.8930622978309, 949.4588084791602, 3556.76881002734, 3624.0, 3679.816009199529, 3828.272628200633, 3830.942535861964, 9122.087791220825, 9170.358074934824, 9550.713478595653, 9564.56959436827, 9598.184154346958, 9201.861972070425, 9215.49314787693, 9223.953880230556, 9245.583948722324, 9341.439513629204, 6828.965855170792, 6932.23745388007, 6964.0, 6985.3713165815325, 7297.4891757577525, 5255.842324730264, 5268.6575372600655, 5281.207818827206, 5282.128448805904, 5284.735763211747, 8699.565021749015, 8755.168259015383, 8763.211310982042, 8839.46963182211, 8905.599248033725, 8843.380963332505, 8912.287017038492, 9039.728808135766, 9079.636814527345, 9095.49974751395, 5593.580481463988, 6094.664793436226, 6261.123442718114, 6422.42198202164, 6512.185976752906, 7343.265673432614, 7382.926170738224, 7426.777196684108, 7439.479236453612, 7485.850282994418, 6483.319251478527, 6524.771632992823, 6620.205575330991, 6732.451536146616, 6825.55633883796, 6550.377714117151, 6649.767258145942, 6716.429103526059, 6735.528513004043, 6796.898046529405, 6118.908216376669, 6200.410960958701, 6233.812985610439, 6238.625682459067, 6247.812565623039, 4453.777311134366, 4617.0, 4620.5379462053525, 4735.905281894412, 4751.453582838018, 4584.770761461847, 4673.392458980311, 4704.5631583386885, 4730.148573256847, 4733.479317275028], "value": 4113.330406417211, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8555400630694, 303.1118821851974, 306.9002574163397, 314.05469536694545, 315.6748548994535, 4801.90396192076, 4895.975520122403, 4945.653804233705, 5013.79668879469, 5016.67058229569, 4916.262560615909, 4953.826616068438, 4955.9840232752285, 4964.354633897588, 5008.449070602231, 3930.6265904739003, 3936.6260205280596, 3945.487086678728, 3953.3279342511696, 3956.1296514766705, 3663.230721548478, 3942.605739426049, 3943.2310699413624, 3943.7830919299427, 3957.663598594123, 3755.1599716139167, 3833.290841194374, 3833.310004199243, 3834.731568790186, 3842.558105817825, 3810.580836108026, 3828.7319887607878, 3838.539375274957, 3841.519810023747, 3860.610180335077, 3368.8652453901814, 3376.3584918865336, 3384.813835239061, 3385.7291416686608, 3390.74569407293, 4690.613307627715, 4815.085133824562, 4858.17411040124, 4859.854204373874, 4869.883274704192, 4381.452318460192, 4385.736855788663, 4406.9779651101535, 4411.602955733967, 4415.889602759925, 5200.375954885438, 5203.7398130093725, 5213.800825810047, 5219.225463342482, 5226.895462090737, 3581.391163502211, 3585.94621080684, 3680.9447858282147, 3684.318401095779, 3687.483752274684, 5452.91820622691, 5459.3994660587305, 5459.771551400942, 5460.706503268597, 5466.617336786427, 3791.431285307226, 3794.392897136445, 3799.955012371565, 3804.942925856059, 3806.1626442182674, 3849.9037524062123, 3856.267309211241, 3858.7684738915755, 3859.9807000964815, 3874.1670540833857, 4307.633851122627, 4331.53967357374, 4388.188185185751, 4402.713823601455, 4423.469183697976, 5188.740562971836, 5222.832524502289, 5226.451222621646, 5226.817061402832, 5227.843164705065, 1253.254313683357, 1288.62629837348, 1299.4282515693064, 1302.5831733845268, 1303.68711509239, 886.577043844633, 894.4543828264754, 906.9183773460288, 909.7816524034184, 914.7513643876194, 4461.620762235181, 4497.707649002804, 4501.662375321798, 4518.277075667877, 4521.756516957863, 5271.551918086929, 5292.788288468418, 5314.627976041717, 5339.5728341732565, 5344.358676958712, 4357.9128417431475, 4510.864674059783, 4522.547745225517, 4532.614727748112, 4548.909021819545, 2503.561876671575, 2527.671402717635, 2529.6711427514306, 2530.4053547416343, 2532.7467253274526, 4459.821607135743, 4462.326627514682, 4487.349334346567, 4491.932621010687, 4503.527129651405, 4596.977015114903, 4793.424789025339, 4802.6638135330195, 4804.807807687724, 4819.95180048202, 4334.696571239983, 4472.753998530047, 4481.731096134243, 4484.080763443421, 4505.707129036602, 4709.434867815884, 4712.340272361804, 4723.31511930775, 4740.597049250852, 4753.382060332135, 4646.514114948486, 4647.9767601161775, 4654.671909584663, 4664.8367307145445, 4668.5098064702715, 4289.635380992526, 4427.317619304644, 4433.645308375258, 4454.331850222363, 4483.237849565647, 3424.760266781301, 3425.5718035245595, 3450.2236996675547, 3458.654134586521, 3458.948115778215, 4675.462321833033, 4678.888539121446, 4682.1103990241745, 4690.0, 5125.718085505333, 5085.262636917552, 5220.363716323232, 5220.530152286313, 5221.895562088813, 5234.214867769865, 5186.17021276609, 5189.948100518946, 5207.973960130175, 5230.293910322058, 5252.579793616578, 4938.209886418156, 5039.370078740158, 5073.34046573943, 5094.541491265803, 5119.92320115206, 5217.061118855392, 5232.293640358503, 5235.60732945023, 5259.894802104013, 5260.631755777212, 5128.435872054146, 5128.897422051464, 5134.743262836918, 5137.6660517066275, 5142.202958541432], "value": 3800.1737210231227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.3195077119796, 656.9770058047969, 674.5210900260814, 739.3235189801333, 762.9313361797438, 12365.31990740509, 12819.807702884464, 12822.807657885127, 12833.42249598768, 12836.165649232806, 12246.387680615955, 12489.437975291112, 12795.616131516046, 12825.551105711302, 12958.676033099176, 615.1940957345869, 757.8067592763838, 763.1224092293878, 766.4979438467799, 767.2212704105337, 757.9431542634297, 761.2577736706722, 761.5887420792793, 773.9226077392273, 777.9494332868345, 814.9185081491863, 820.8604537228656, 823.086374124721, 824.9051359093721, 825.4469505431346, 801.9799505012393, 802.6949759091566, 807.8707406814881, 808.337163525909, 813.686730608717, 596.3201949777257, 596.5406636889614, 599.5892813422819, 599.8170557979814, 601.1913975702673, 12410.875891241063, 12455.564055258023, 12492.188007779556, 12550.058745594026, 12582.811257831048, 11204.327740335606, 11357.091432685447, 11662.941685291604, 11714.121440891968, 11973.102017348734, 10391.584336626525, 10503.52734126966, 10589.523471443803, 10645.201609879212, 10778.78442431147, 3243.189202699325, 3307.3054658521737, 3334.4664853623303, 3343.8160901150427, 3415.316936612687, 62532.37467625357, 63442.365576344106, 63649.68175159141, 64090.679546602434, 65193.0, 10098.141657959155, 10211.387316761018, 10229.795404091876, 10282.691519254435, 10445.68662940113, 19697.803021969597, 20134.69797953032, 20164.092615832324, 20211.8989405052, 20241.291554795807, 6814.182298124158, 6916.20463646677, 7027.402670773042, 7054.0, 7225.6387180641805, 3779.074126838942, 3817.84728610858, 3843.7117216208335, 3845.5577608575372, 3887.688984881203, 2871.870765815543, 2935.207493976657, 2953.822770633769, 2965.0, 2976.1964269647074, 516.5144763921901, 525.9342582177228, 528.3026405145248, 529.5472371122712, 539.5629540072554, 532.3212903548007, 632.3771085480797, 633.297040285283, 645.6964332722446, 665.8601693644292, 8497.447665901696, 8733.781655458537, 8789.0, 8861.291096712248, 8904.55477226149, 484.15998243048284, 484.4646665434714, 593.8716438766293, 648.7235991779514, 657.9671016449158, 4095.4266402703647, 4134.64855487281, 4166.8333266669, 4190.371444283321, 4302.741835489881, 52926.94146117056, 84454.73317900236, 94070.5296473527, 97036.0, 97084.51457742666, 53512.0, 77371.61314193394, 78127.61631383865, 78517.60741196257, 78830.0, 44391.55608443939, 52364.73817630888, 68855.0, 71764.28235717681, 72336.27663723401, 7754.263344982218, 7994.880076798736, 8109.59452027408, 8131.105578386491, 8204.641876715747, 43283.783581081894, 43817.99999999873, 43990.580518071816, 44337.334939975284, 45842.312365315214, 35762.28475430529, 37899.431508527945, 39536.40695389629, 42638.78680606577, 62325.0, 11221.326720396803, 11350.602728904449, 11629.592964246549, 11795.0, 11854.288742675468, 12416.379181041093, 12505.43725532335, 12620.242785432903, 12819.0, 13401.66495837612, 12265.0, 12407.25556466615, 12677.049221308254, 12703.809442858179, 12807.80788288195, 12703.301318427575, 12785.424655890693, 12810.103292769416, 13137.211767293993, 13797.310134493035, 11872.109591780478, 12463.915775173995, 12638.304893230592, 12858.807117893051, 12955.870441295467, 10343.84484232722, 10495.580176792842, 10773.19201059908, 11312.6040588579, 11318.660440186808, 10416.0, 11074.944625276821, 11416.372099534605, 11537.461758413472, 11595.246308989888], "value": 6519.880847475675, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 113.0, 110.0, 117.0, 114.0, 116.0, 115.0, 116.0, 115.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 147.0, 177.0, 128.0, 154.0, 157.0, 126.0, 197.0, 125.0], "value": 149.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 165.0, 171.0, 167.0, 165.0, 165.0, 165.0, 163.0, 163.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 76.0, 165.0, 79.0, 76.0, 77.0, 77.0, 163.0, 78.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 145.0, 86.0, 89.0, 88.0, 140.0, 88.0, 87.0, 141.0], "value": 103.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 119.0, 119.0, 119.0, 118.0, 119.0, 119.0, 119.0, 118.0], "value": 118.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 151.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 150.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 95.0, 96.0, 95.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 66.0, 64.0, 66.0, 65.0, 65.0, 65.0, 67.0, 63.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 84.0, 87.0, 83.0, 79.0, 84.0, 82.0, 92.0, 81.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 133.0, 138.0, 138.0, 131.0, 131.0, 133.0, 182.0, 143.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 171.0, 177.0, 173.0, 177.0, 175.0, 180.0, 172.0, 171.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 69.0, 68.0, 69.0, 69.0, 68.0, 68.0, 68.0, 76.0], "value": 70.4, "unit": "score"}]}]} 22:49:43 INFO - Return code: 0 22:49:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:49:43 ERROR - # TBPL SUCCESS # 22:49:43 INFO - Running post-action listener: _resource_record_post_action 22:49:43 INFO - ##### 22:49:43 INFO - ##### Finished run-tests step (success) 22:49:43 INFO - ##### 22:49:43 INFO - Running post-run listener: _resource_record_post_run 22:49:43 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 12288; Write bytes: 534171648; Read time: 324; Write time: 617764 22:49:43 INFO - TinderboxPrint: CPU usage
13.4% 22:49:43 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 324 22:49:43 INFO - TinderboxPrint: I/O write bytes / time
534,171,648 / 617,764 22:49:43 INFO - TinderboxPrint: CPU idle
8,266.7 (86.5%) 22:49:43 INFO - TinderboxPrint: CPU user
1,224.7 (12.8%) 22:49:43 INFO - TinderboxPrint: Swap in / out
0 / 0 22:49:43 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 245567488; Read time: 88; Write time: 326668 22:49:43 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288268288; Read time: 224; Write time: 291056 22:49:43 INFO - Running post-run listener: _upload_blobber_files 22:49:43 INFO - Blob upload gear active. 22:49:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:49:43 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 22:49:43 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'] 22:49:43 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 22:49:44 INFO - (blobuploader) - INFO - Open directory for files ... 22:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:49:44 INFO - (blobuploader) - INFO - Done attempting. 22:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:49:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:49:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:49:49 INFO - (blobuploader) - INFO - Done attempting. 22:49:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:49:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:49:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:49:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:49:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:49:50 INFO - (blobuploader) - INFO - Done attempting. 22:49:50 INFO - (blobuploader) - INFO - Iteration through files over. 22:49:50 INFO - Return code: 0 22:49:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:49:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:49:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31cbbd39cece994993d33fc5324adca16b2e487d7b62fdf8ea045656ebdc1b656fb14ddd96f40368d9faa31497bac11981ac50a8bb94ca84652fd81644c3bcab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e6247644d8d68ff6df86e019cf87c778fc2545e2b7c6cd72c5c8a5a771b35a1a6a0534359e9ede0e632b4bf8d34fc160207c082a14b6b20edf87900bd2fd1d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e12575601ed71c2b081d112458f559abe805d0ecccecf5e3ea788de0f7c473966ec31df45c807b3164caac02d069feacb111b8e51da6383c247c4eb01f356fcf"} 22:49:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:49:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:49:50 INFO - Contents: 22:49:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31cbbd39cece994993d33fc5324adca16b2e487d7b62fdf8ea045656ebdc1b656fb14ddd96f40368d9faa31497bac11981ac50a8bb94ca84652fd81644c3bcab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e6247644d8d68ff6df86e019cf87c778fc2545e2b7c6cd72c5c8a5a771b35a1a6a0534359e9ede0e632b4bf8d34fc160207c082a14b6b20edf87900bd2fd1d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e12575601ed71c2b081d112458f559abe805d0ecccecf5e3ea788de0f7c473966ec31df45c807b3164caac02d069feacb111b8e51da6383c247c4eb01f356fcf"} 22:49:50 INFO - Running post-run listener: copy_logs_to_upload_dir 22:49:50 INFO - Copying logs to upload dir... 22:49:50 INFO - mkdir: /builds/slave/test/build/upload/logs 22:49:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.603314 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-11 22:49:49.243525) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 22:49:49.246874) ========= 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=52faae308a556c440401f8e5000001e8-1468299872.317980-770607251 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/31cbbd39cece994993d33fc5324adca16b2e487d7b62fdf8ea045656ebdc1b656fb14ddd96f40368d9faa31497bac11981ac50a8bb94ca84652fd81644c3bcab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e6247644d8d68ff6df86e019cf87c778fc2545e2b7c6cd72c5c8a5a771b35a1a6a0534359e9ede0e632b4bf8d34fc160207c082a14b6b20edf87900bd2fd1d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e12575601ed71c2b081d112458f559abe805d0ecccecf5e3ea788de0f7c473966ec31df45c807b3164caac02d069feacb111b8e51da6383c247c4eb01f356fcf"} build_url:https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/aItLOdztQ0iwVNw4GmRe4A/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/31cbbd39cece994993d33fc5324adca16b2e487d7b62fdf8ea045656ebdc1b656fb14ddd96f40368d9faa31497bac11981ac50a8bb94ca84652fd81644c3bcab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e6247644d8d68ff6df86e019cf87c778fc2545e2b7c6cd72c5c8a5a771b35a1a6a0534359e9ede0e632b4bf8d34fc160207c082a14b6b20edf87900bd2fd1d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e12575601ed71c2b081d112458f559abe805d0ecccecf5e3ea788de0f7c473966ec31df45c807b3164caac02d069feacb111b8e51da6383c247c4eb01f356fcf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 22:49:49.271362) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 22:49:49.271665) ========= 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=52faae308a556c440401f8e5000001e8-1468299872.317980-770607251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004436 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 22:49:49.358336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 22:49:49.358659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 22:49:49.358951) ========= ========= Total master_lag: 0.16 =========