builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-048 starttime: 1467528551.46 results: success (0) buildid: 20160702144223 builduid: bd7aa9a48dd24fd185509157046c588d revision: b8b3b1c92085c1f8b74c13203289360932182da4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.463725) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.464264) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.464618) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.479877) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.480185) ========= 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=fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-02 23:49:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-07-02 23:49:13 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.678099) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.678676) ========= 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=fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020468 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:11.730837) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 23:49:11.731613) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b8b3b1c92085c1f8b74c13203289360932182da4 --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 b8b3b1c92085c1f8b74c13203289360932182da4 --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=fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-02 23:49:13,488 truncating revision to first 12 chars 2016-07-02 23:49:13,488 Setting DEBUG logging. 2016-07-02 23:49:13,488 attempt 1/10 2016-07-02 23:49:13,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b8b3b1c92085?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-02 23:49:14,829 unpacking tar archive at: autoland-b8b3b1c92085/testing/mozharness/ program finished with exit code 0 elapsedTime=1.683586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-02 23:49:13.435278) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:13.436105) ========= script_repo_revision: b8b3b1c92085c1f8b74c13203289360932182da4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:13.437283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:13.437972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-02 23:49:13.449619) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-02 23:49:13.450235) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:49:15 INFO - MultiFileLogger online at 20160702 23:49:15 in /builds/slave/test 23:49:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 23:49:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:49:15 INFO - {'append_to_log': False, 23:49:15 INFO - 'base_work_dir': '/builds/slave/test', 23:49:15 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 23:49:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:49:15 INFO - 'branch': 'Autoland-Non-PGO', 23:49:15 INFO - 'buildbot_json_path': 'buildprops.json', 23:49:15 INFO - 'config_files': ('talos/linux_config.py',), 23:49:15 INFO - 'default_actions': ('clobber', 23:49:15 INFO - 'read-buildbot-config', 23:49:15 INFO - 'download-and-extract', 23:49:15 INFO - 'populate-webroot', 23:49:15 INFO - 'create-virtualenv', 23:49:15 INFO - 'install', 23:49:15 INFO - 'run-tests'), 23:49:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:49:15 INFO - 'download_minidump_stackwalk': True, 23:49:15 INFO - 'download_symbols': 'ondemand', 23:49:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:49:15 INFO - 'tooltool.py': '/tools/tooltool.py', 23:49:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:49:15 INFO - '/tools/misc-python/virtualenv.py')}, 23:49:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:49:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:49:15 INFO - 'installer_path': 'installer.exe', 23:49:15 INFO - 'log_level': 'info', 23:49:15 INFO - 'log_name': 'talos', 23:49:15 INFO - 'log_to_console': True, 23:49:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:49:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:49:15 INFO - 'opt_config_files': (), 23:49:15 INFO - 'pip_index': False, 23:49:15 INFO - 'sps_profile': False, 23:49:15 INFO - 'sps_profile_interval': 0, 23:49:15 INFO - 'suite': 'dromaeojs', 23:49:15 INFO - 'system_bits': '32', 23:49:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:49:15 INFO - 'title': 'talos-linux64-ix-048', 23:49:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:49:15 INFO - 'use_talos_json': True, 23:49:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:49:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:49:15 INFO - 'work_dir': 'build'} 23:49:15 INFO - ##### 23:49:15 INFO - ##### Running clobber step. 23:49:15 INFO - ##### 23:49:15 INFO - Running pre-action listener: _resource_record_pre_action 23:49:15 INFO - Running main action method: clobber 23:49:15 INFO - rmtree: /builds/slave/test/build 23:49:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:49:15 INFO - Running post-action listener: _resource_record_post_action 23:49:15 INFO - ##### 23:49:15 INFO - ##### Finished clobber step (success) 23:49:15 INFO - ##### 23:49:15 INFO - ##### 23:49:15 INFO - ##### Running read-buildbot-config step. 23:49:15 INFO - ##### 23:49:15 INFO - Running pre-action listener: _resource_record_pre_action 23:49:15 INFO - Running main action method: read_buildbot_config 23:49:15 INFO - Using buildbot properties: 23:49:15 INFO - { 23:49:15 INFO - "project": "", 23:49:15 INFO - "product": "firefox", 23:49:15 INFO - "script_repo_revision": "production", 23:49:15 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 23:49:15 INFO - "repository": "", 23:49:15 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 23:49:15 INFO - "buildid": "20160702144223", 23:49:15 INFO - "pgo_build": "False", 23:49:15 INFO - "basedir": "/builds/slave/test", 23:49:15 INFO - "buildnumber": 12, 23:49:15 INFO - "slavename": "talos-linux64-ix-048", 23:49:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:49:15 INFO - "platform": "ubuntu64_hw", 23:49:15 INFO - "scheduler": "tests-autoland-linux64-talos", 23:49:15 INFO - "branch": "autoland", 23:49:15 INFO - "repo_path": "integration/autoland", 23:49:15 INFO - "revision": "b8b3b1c92085c1f8b74c13203289360932182da4", 23:49:15 INFO - "stage_platform": "linux64", 23:49:15 INFO - "builduid": "bd7aa9a48dd24fd185509157046c588d", 23:49:15 INFO - "slavebuilddir": "test" 23:49:15 INFO - } 23:49:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:49:15 INFO - Running post-action listener: _resource_record_post_action 23:49:15 INFO - ##### 23:49:15 INFO - ##### Finished read-buildbot-config step (success) 23:49:15 INFO - ##### 23:49:15 INFO - ##### 23:49:15 INFO - ##### Running download-and-extract step. 23:49:15 INFO - ##### 23:49:15 INFO - Running pre-action listener: _resource_record_pre_action 23:49:15 INFO - Running main action method: download_and_extract 23:49:15 INFO - mkdir: /builds/slave/test/build/tests 23:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:15 INFO - https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:49:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:49:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:49:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:16 INFO - Downloaded 1361 bytes. 23:49:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:49:16 INFO - Using the following test package requirements: 23:49:16 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:49:16 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:49:16 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:49:16 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'jsshell-linux-x86_64.zip'], 23:49:16 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:49:16 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:49:16 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:49:16 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:49:16 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:49:16 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:49:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:49:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:49: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')]} 23:49:16 INFO - https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:49:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:49:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:49:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:17 INFO - Downloaded 22705905 bytes. 23:49:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:49:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:49:18 INFO - Return code: 0 23:49:18 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 23:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:18 INFO - https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:49:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:49:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:49:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:19 INFO - Downloaded 22705905 bytes. 23:49:19 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'] 23:49:19 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 23:49:21 INFO - Return code: 0 23:49: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')]} 23:49:21 INFO - https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:49:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:49:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:49:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:22 INFO - Downloaded 14405327 bytes. 23:49:22 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'] 23:49:22 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 23:49:22 INFO - Return code: 0 23:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:22 INFO - https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:49:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:49:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:49:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/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 23:49:24 INFO - Downloaded 56486849 bytes. 23:49:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:49:24 INFO - mkdir: /builds/slave/test/properties 23:49:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:49:24 INFO - Writing to file /builds/slave/test/properties/build_url 23:49:24 INFO - Contents: 23:49:24 INFO - build_url:https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:49:24 INFO - Running post-action listener: _resource_record_post_action 23:49:24 INFO - Running post-action listener: set_extra_try_arguments 23:49:24 INFO - ##### 23:49:24 INFO - ##### Finished download-and-extract step (success) 23:49:24 INFO - ##### 23:49:24 INFO - ##### 23:49:24 INFO - ##### Running populate-webroot step. 23:49:24 INFO - ##### 23:49:24 INFO - Running pre-action listener: _resource_record_pre_action 23:49:24 INFO - Running main action method: populate_webroot 23:49:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:49:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:49:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:49:24 INFO - '--tppagecycles', 23:49:24 INFO - '7'], 23:49:24 INFO - 'tests': ['tsvgm']}}, 23:49:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:49:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:49:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:49:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:49:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:49:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:49:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['damp', 'tps']}, 23:49:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'tests': ['damp', 'tps']}, 23:49:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['dromaeo_dom']}, 23:49:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:49:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['basic_compositor_video']}, 23:49:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:49:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['a11yr', 23:49:24 INFO - 'ts_paint', 23:49:24 INFO - 'tpaint', 23:49:24 INFO - 'sessionrestore', 23:49:24 INFO - 'sessionrestore_no_auto_restore', 23:49:24 INFO - 'tabpaint']}, 23:49:24 INFO - 'other-e10s': {'tests': ['a11yr', 23:49:24 INFO - 'ts_paint', 23:49:24 INFO - 'tpaint', 23:49:24 INFO - 'sessionrestore', 23:49:24 INFO - 'sessionrestore_no_auto_restore', 23:49:24 INFO - 'tabpaint']}, 23:49:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:49:24 INFO - 'ts_paint', 23:49:24 INFO - 'tpaint', 23:49:24 INFO - 'sessionrestore', 23:49:24 INFO - 'sessionrestore_no_auto_restore', 23:49:24 INFO - 'tabpaint']}, 23:49:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:49:24 INFO - 'ts_paint', 23:49:24 INFO - 'tpaint', 23:49:24 INFO - 'sessionrestore', 23:49:24 INFO - 'sessionrestore_no_auto_restore', 23:49:24 INFO - 'tabpaint']}, 23:49:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['a11yr', 23:49:24 INFO - 'ts_paint', 23:49:24 INFO - 'tpaint', 23:49:24 INFO - 'sessionrestore', 23:49:24 INFO - 'sessionrestore_no_auto_restore', 23:49:24 INFO - 'tabpaint']}, 23:49:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['a11yr', 23:49:24 INFO - 'ts_paint', 23:49:24 INFO - 'tpaint', 23:49:24 INFO - 'sessionrestore', 23:49:24 INFO - 'sessionrestore_no_auto_restore', 23:49:24 INFO - 'tabpaint']}, 23:49:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['tsvgx', 23:49:24 INFO - 'tsvgr_opacity', 23:49:24 INFO - 'tart', 23:49:24 INFO - 'tscrollx', 23:49:24 INFO - 'cart']}, 23:49:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:49:24 INFO - 'tsvgr_opacity', 23:49:24 INFO - 'tart', 23:49:24 INFO - 'tscrollx', 23:49:24 INFO - 'cart']}, 23:49:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'talos_options': ['--disable-e10s'], 23:49:24 INFO - 'tests': ['tp5o']}, 23:49:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'tests': ['tp5o']}, 23:49:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'talos_options': ['--disable-e10s', 23:49:24 INFO - '--xperf_path', 23:49:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:49:24 INFO - 'tests': ['tp5n']}, 23:49:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:49:24 INFO - 'talos_options': ['--xperf_path', 23:49:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:49:24 INFO - 'tests': ['tp5n']}}, 23:49:24 INFO - 'talos.zip': {'path': '', 23:49:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:49:24 INFO - Running post-action listener: _resource_record_post_action 23:49:24 INFO - ##### 23:49:24 INFO - ##### Finished populate-webroot step (success) 23:49:24 INFO - ##### 23:49:24 INFO - ##### 23:49:24 INFO - ##### Running create-virtualenv step. 23:49:24 INFO - ##### 23:49:24 INFO - Running pre-action listener: _resource_record_pre_action 23:49:24 INFO - Running main action method: create_virtualenv 23:49:24 INFO - Creating virtualenv /builds/slave/test/build/venv 23:49:24 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 23:49:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:49:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:49:25 INFO - Using real prefix '/usr' 23:49:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:49:25 INFO - Installing distribute.............................................................................................................................................................................................done. 23:49:27 INFO - Installing pip.................done. 23:49:27 INFO - Return code: 0 23:49:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:49:27 INFO - Using env: {'DISPLAY': ':0', 23:49:27 INFO - 'HOME': '/home/cltbld', 23:49:27 INFO - 'LANG': 'en_US.UTF-8', 23:49:27 INFO - 'LANGUAGE': 'en_US:en', 23:49:27 INFO - 'LOGNAME': 'cltbld', 23:49:27 INFO - 'MAIL': '/var/mail/cltbld', 23:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:27 INFO - 'MOZ_NO_REMOTE': '1', 23:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:27 INFO - 'NO_EM_RESTART': '1', 23:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:27 INFO - 'PWD': '/builds/slave/test', 23:49:27 INFO - 'SHELL': '/bin/bash', 23:49:27 INFO - 'SHLVL': '1', 23:49:27 INFO - 'TERM': 'linux', 23:49:27 INFO - 'TMOUT': '86400', 23:49:27 INFO - 'USER': 'cltbld', 23:49:27 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:27 INFO - '_': '/tools/buildbot/bin/python'} 23:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:27 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 23:49:27 INFO - Cleaning up... 23:49:27 INFO - Return code: 0 23:49:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:49:27 INFO - Using env: {'DISPLAY': ':0', 23:49:27 INFO - 'HOME': '/home/cltbld', 23:49:27 INFO - 'LANG': 'en_US.UTF-8', 23:49:27 INFO - 'LANGUAGE': 'en_US:en', 23:49:27 INFO - 'LOGNAME': 'cltbld', 23:49:27 INFO - 'MAIL': '/var/mail/cltbld', 23:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:27 INFO - 'MOZ_NO_REMOTE': '1', 23:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:27 INFO - 'NO_EM_RESTART': '1', 23:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:27 INFO - 'PWD': '/builds/slave/test', 23:49:27 INFO - 'SHELL': '/bin/bash', 23:49:27 INFO - 'SHLVL': '1', 23:49:27 INFO - 'TERM': 'linux', 23:49:27 INFO - 'TMOUT': '86400', 23:49:27 INFO - 'USER': 'cltbld', 23:49:27 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:27 INFO - '_': '/tools/buildbot/bin/python'} 23:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:27 INFO - Downloading/unpacking psutil>=3.1.1 23:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:49:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:49:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:49:29 INFO - Installing collected packages: psutil 23:49:29 INFO - Running setup.py install for psutil 23:49:29 INFO - building 'psutil._psutil_linux' extension 23:49:29 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 23:49:30 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 23:49:30 INFO - building 'psutil._psutil_posix' extension 23:49:30 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 23:49:30 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 23:49:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:49:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:49:30 INFO - Successfully installed psutil 23:49:30 INFO - Cleaning up... 23:49:30 INFO - Return code: 0 23:49:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:49:30 INFO - Using env: {'DISPLAY': ':0', 23:49:30 INFO - 'HOME': '/home/cltbld', 23:49:30 INFO - 'LANG': 'en_US.UTF-8', 23:49:30 INFO - 'LANGUAGE': 'en_US:en', 23:49:30 INFO - 'LOGNAME': 'cltbld', 23:49:30 INFO - 'MAIL': '/var/mail/cltbld', 23:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:30 INFO - 'MOZ_NO_REMOTE': '1', 23:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:30 INFO - 'NO_EM_RESTART': '1', 23:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:30 INFO - 'PWD': '/builds/slave/test', 23:49:30 INFO - 'SHELL': '/bin/bash', 23:49:30 INFO - 'SHLVL': '1', 23:49:30 INFO - 'TERM': 'linux', 23:49:30 INFO - 'TMOUT': '86400', 23:49:30 INFO - 'USER': 'cltbld', 23:49:30 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:30 INFO - '_': '/tools/buildbot/bin/python'} 23:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:49:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:49:32 INFO - Installing collected packages: mozsystemmonitor 23:49:32 INFO - Running setup.py install for mozsystemmonitor 23:49:32 INFO - Successfully installed mozsystemmonitor 23:49:32 INFO - Cleaning up... 23:49:32 INFO - Return code: 0 23:49:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:49:32 INFO - Using env: {'DISPLAY': ':0', 23:49:32 INFO - 'HOME': '/home/cltbld', 23:49:32 INFO - 'LANG': 'en_US.UTF-8', 23:49:32 INFO - 'LANGUAGE': 'en_US:en', 23:49:32 INFO - 'LOGNAME': 'cltbld', 23:49:32 INFO - 'MAIL': '/var/mail/cltbld', 23:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:32 INFO - 'MOZ_NO_REMOTE': '1', 23:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:32 INFO - 'NO_EM_RESTART': '1', 23:49:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:32 INFO - 'PWD': '/builds/slave/test', 23:49:32 INFO - 'SHELL': '/bin/bash', 23:49:32 INFO - 'SHLVL': '1', 23:49:32 INFO - 'TERM': 'linux', 23:49:32 INFO - 'TMOUT': '86400', 23:49:32 INFO - 'USER': 'cltbld', 23:49:32 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:32 INFO - '_': '/tools/buildbot/bin/python'} 23:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:32 INFO - Downloading/unpacking jsonschema==2.5.1 23:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:49:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49: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 23:49:35 INFO - Downloading functools32-3.2.3-2.tar.gz 23:49:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:49:35 INFO - warning: no files found matching '*.txt' 23:49:35 INFO - no previously-included directories found matching 'build' 23:49:35 INFO - no previously-included directories found matching 'dist' 23:49:35 INFO - no previously-included directories found matching '.git*' 23:49:35 INFO - Installing collected packages: jsonschema, functools32 23:49:35 INFO - Running setup.py install for functools32 23:49:35 INFO - warning: no files found matching '*.txt' 23:49:35 INFO - no previously-included directories found matching 'build' 23:49:35 INFO - no previously-included directories found matching 'dist' 23:49:35 INFO - no previously-included directories found matching '.git*' 23:49:35 INFO - Successfully installed jsonschema functools32 23:49:35 INFO - Cleaning up... 23:49:35 INFO - Return code: 0 23:49:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:49:35 INFO - Using env: {'DISPLAY': ':0', 23:49:35 INFO - 'HOME': '/home/cltbld', 23:49:35 INFO - 'LANG': 'en_US.UTF-8', 23:49:35 INFO - 'LANGUAGE': 'en_US:en', 23:49:35 INFO - 'LOGNAME': 'cltbld', 23:49:35 INFO - 'MAIL': '/var/mail/cltbld', 23:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:35 INFO - 'MOZ_NO_REMOTE': '1', 23:49:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:35 INFO - 'NO_EM_RESTART': '1', 23:49:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:35 INFO - 'PWD': '/builds/slave/test', 23:49:35 INFO - 'SHELL': '/bin/bash', 23:49:35 INFO - 'SHLVL': '1', 23:49:35 INFO - 'TERM': 'linux', 23:49:35 INFO - 'TMOUT': '86400', 23:49:35 INFO - 'USER': 'cltbld', 23:49:35 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:35 INFO - '_': '/tools/buildbot/bin/python'} 23:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:35 INFO - Downloading/unpacking blobuploader==1.2.4 23:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:37 INFO - Downloading blobuploader-1.2.4.tar.gz 23:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:49:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:49:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:38 INFO - Downloading docopt-0.6.1.tar.gz 23:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:49:38 INFO - Installing collected packages: blobuploader, requests, docopt 23:49:38 INFO - Running setup.py install for blobuploader 23:49:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:49:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:49:38 INFO - Running setup.py install for requests 23:49:38 INFO - Running setup.py install for docopt 23:49:38 INFO - Successfully installed blobuploader requests docopt 23:49:38 INFO - Cleaning up... 23:49:38 INFO - Return code: 0 23:49:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:38 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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:38 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 23:49:38 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 23:49:38 INFO - Using env: {'DISPLAY': ':0', 23:49:38 INFO - 'HOME': '/home/cltbld', 23:49:38 INFO - 'LANG': 'en_US.UTF-8', 23:49:38 INFO - 'LANGUAGE': 'en_US:en', 23:49:38 INFO - 'LOGNAME': 'cltbld', 23:49:38 INFO - 'MAIL': '/var/mail/cltbld', 23:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:38 INFO - 'MOZ_NO_REMOTE': '1', 23:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:38 INFO - 'NO_EM_RESTART': '1', 23:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:38 INFO - 'PWD': '/builds/slave/test', 23:49:38 INFO - 'SHELL': '/bin/bash', 23:49:38 INFO - 'SHLVL': '1', 23:49:38 INFO - 'TERM': 'linux', 23:49:38 INFO - 'TMOUT': '86400', 23:49:38 INFO - 'USER': 'cltbld', 23:49:38 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:38 INFO - '_': '/tools/buildbot/bin/python'} 23:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:49:38 INFO - Running setup.py (path:/tmp/pip-4VMdsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:49:39 INFO - Running setup.py (path:/tmp/pip-ms23lA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:49:39 INFO - Running setup.py (path:/tmp/pip-6_Vjze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:49:39 INFO - Running setup.py (path:/tmp/pip-Ce8gAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:49:39 INFO - Running setup.py (path:/tmp/pip-wYwRwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:49:39 INFO - Running setup.py (path:/tmp/pip-qxXQHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:49:39 INFO - Running setup.py (path:/tmp/pip-arytBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:49:39 INFO - Running setup.py (path:/tmp/pip-Ota7_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:49:39 INFO - Running setup.py (path:/tmp/pip-NFZP3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:49:39 INFO - Running setup.py (path:/tmp/pip-MfQUyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:49:39 INFO - Running setup.py (path:/tmp/pip-e7mgdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:49:39 INFO - Running setup.py (path:/tmp/pip-nsuFwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:49:39 INFO - Running setup.py (path:/tmp/pip-c9C99X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:49:39 INFO - Running setup.py (path:/tmp/pip-DJN2mY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:40 INFO - Running setup.py (path:/tmp/pip-IcuOlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:49:40 INFO - Running setup.py (path:/tmp/pip-zPUKBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:49:40 INFO - Running setup.py (path:/tmp/pip-3qqF7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:49:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:49:40 INFO - Running setup.py install for manifestparser 23:49:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:49:40 INFO - Running setup.py install for mozcrash 23:49:40 INFO - Running setup.py install for mozdebug 23:49:40 INFO - Running setup.py install for mozdevice 23:49:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:49:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:49:40 INFO - Running setup.py install for mozfile 23:49:40 INFO - Running setup.py install for mozhttpd 23:49:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:49:40 INFO - Running setup.py install for mozinfo 23:49:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:49:40 INFO - Running setup.py install for mozInstall 23:49:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Running setup.py install for mozleak 23:49:41 INFO - Running setup.py install for mozlog 23:49:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Running setup.py install for moznetwork 23:49:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Running setup.py install for mozprocess 23:49:41 INFO - Running setup.py install for mozprofile 23:49:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Running setup.py install for mozrunner 23:49:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Running setup.py install for mozscreenshot 23:49:41 INFO - Running setup.py install for moztest 23:49:41 INFO - Running setup.py install for mozversion 23:49:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:49:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:49:41 INFO - Cleaning up... 23:49:42 INFO - Return code: 0 23:49:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:42 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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:42 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 23:49:42 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 23:49:42 INFO - Using env: {'DISPLAY': ':0', 23:49:42 INFO - 'HOME': '/home/cltbld', 23:49:42 INFO - 'LANG': 'en_US.UTF-8', 23:49:42 INFO - 'LANGUAGE': 'en_US:en', 23:49:42 INFO - 'LOGNAME': 'cltbld', 23:49:42 INFO - 'MAIL': '/var/mail/cltbld', 23:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:42 INFO - 'MOZ_NO_REMOTE': '1', 23:49:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:42 INFO - 'NO_EM_RESTART': '1', 23:49:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:42 INFO - 'PWD': '/builds/slave/test', 23:49:42 INFO - 'SHELL': '/bin/bash', 23:49:42 INFO - 'SHLVL': '1', 23:49:42 INFO - 'TERM': 'linux', 23:49:42 INFO - 'TMOUT': '86400', 23:49:42 INFO - 'USER': 'cltbld', 23:49:42 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:42 INFO - '_': '/tools/buildbot/bin/python'} 23:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:49:42 INFO - Running setup.py (path:/tmp/pip-Fygl35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:49:42 INFO - Running setup.py (path:/tmp/pip-CvdDwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:49:42 INFO - Running setup.py (path:/tmp/pip-ENXAB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:49:42 INFO - Running setup.py (path:/tmp/pip-Ik9dIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:49:42 INFO - Running setup.py (path:/tmp/pip-rW6uPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:49:42 INFO - Running setup.py (path:/tmp/pip-BovJa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:49:42 INFO - Running setup.py (path:/tmp/pip-1lKVQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:49:42 INFO - Running setup.py (path:/tmp/pip-XznFxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:49:42 INFO - Running setup.py (path:/tmp/pip-Zemqp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:49:42 INFO - Running setup.py (path:/tmp/pip-uOCS7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:49:42 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)) 23:49:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:49:42 INFO - Running setup.py (path:/tmp/pip-QItOiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:49:43 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)) 23:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:49:43 INFO - Running setup.py (path:/tmp/pip-Bn9G9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:49:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 23:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:49:43 INFO - Running setup.py (path:/tmp/pip-ASP8lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:49:43 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)) 23:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:49:43 INFO - Running setup.py (path:/tmp/pip-r4hTVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:49:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 23:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:43 INFO - Running setup.py (path:/tmp/pip-8KgX1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:43 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)) 23:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:49:43 INFO - Running setup.py (path:/tmp/pip-XbhW8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:49:43 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)) 23:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:49:43 INFO - Running setup.py (path:/tmp/pip-6iEZBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:49:43 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)) 23:49:43 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)) 23:49:43 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)) 23:49:43 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)) 23:49:43 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)) 23:49:43 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)) 23:49:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:49:43 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 23:49:43 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 23:49:43 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 23:49:43 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 23:49:43 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 23:49:43 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 23:49:45 INFO - Downloading blessings-1.6.tar.gz 23:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:49:45 INFO - Installing collected packages: blessings 23:49:45 INFO - Running setup.py install for blessings 23:49:45 INFO - Successfully installed blessings 23:49:45 INFO - Cleaning up... 23:49:45 INFO - Return code: 0 23:49:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:49:45 INFO - Reading from file tmpfile_stdout 23:49:45 INFO - Current package versions: 23:49:45 INFO - argparse == 1.2.1 23:49:45 INFO - blessings == 1.6 23:49:45 INFO - blobuploader == 1.2.4 23:49:45 INFO - docopt == 0.6.1 23:49:45 INFO - functools32 == 3.2.3-2 23:49:45 INFO - jsonschema == 2.5.1 23:49:45 INFO - manifestparser == 1.1 23:49:45 INFO - mozInstall == 1.12 23:49:45 INFO - mozcrash == 0.17 23:49:45 INFO - mozdebug == 0.1 23:49:45 INFO - mozdevice == 0.48 23:49:45 INFO - mozfile == 1.2 23:49:45 INFO - mozhttpd == 0.7 23:49:45 INFO - mozinfo == 0.9 23:49:45 INFO - mozleak == 0.1 23:49:45 INFO - mozlog == 3.2 23:49:45 INFO - moznetwork == 0.27 23:49:45 INFO - mozprocess == 0.22 23:49:45 INFO - mozprofile == 0.28 23:49:45 INFO - mozrunner == 6.11 23:49:45 INFO - mozscreenshot == 0.1 23:49:45 INFO - mozsystemmonitor == 0.3 23:49:45 INFO - moztest == 0.7 23:49:45 INFO - mozversion == 1.4 23:49:45 INFO - psutil == 3.1.1 23:49:45 INFO - requests == 1.2.3 23:49:45 INFO - wsgiref == 0.1.2 23:49:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:45 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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:45 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 23:49:45 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 23:49:45 INFO - Using env: {'DISPLAY': ':0', 23:49:45 INFO - 'HOME': '/home/cltbld', 23:49:45 INFO - 'LANG': 'en_US.UTF-8', 23:49:45 INFO - 'LANGUAGE': 'en_US:en', 23:49:45 INFO - 'LOGNAME': 'cltbld', 23:49:45 INFO - 'MAIL': '/var/mail/cltbld', 23:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:45 INFO - 'MOZ_NO_REMOTE': '1', 23:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:45 INFO - 'NO_EM_RESTART': '1', 23:49:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:45 INFO - 'PWD': '/builds/slave/test', 23:49:45 INFO - 'SHELL': '/bin/bash', 23:49:45 INFO - 'SHLVL': '1', 23:49:45 INFO - 'TERM': 'linux', 23:49:45 INFO - 'TMOUT': '86400', 23:49:45 INFO - 'USER': 'cltbld', 23:49:45 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:45 INFO - '_': '/tools/buildbot/bin/python'} 23:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 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)) 23:49:46 INFO - Cleaning up... 23:49:46 INFO - Return code: 0 23:49:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:49:46 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')]} 23:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:46 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')]} 23:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:46 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 0x18af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b70180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, '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': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', '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 23:49:46 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 23:49:46 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 23:49:46 INFO - Using env: {'DISPLAY': ':0', 23:49:46 INFO - 'HOME': '/home/cltbld', 23:49:46 INFO - 'LANG': 'en_US.UTF-8', 23:49:46 INFO - 'LANGUAGE': 'en_US:en', 23:49:46 INFO - 'LOGNAME': 'cltbld', 23:49:46 INFO - 'MAIL': '/var/mail/cltbld', 23:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:46 INFO - 'MOZ_NO_REMOTE': '1', 23:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:46 INFO - 'NO_EM_RESTART': '1', 23:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:46 INFO - 'PWD': '/builds/slave/test', 23:49:46 INFO - 'SHELL': '/bin/bash', 23:49:46 INFO - 'SHLVL': '1', 23:49:46 INFO - 'TERM': 'linux', 23:49:46 INFO - 'TMOUT': '86400', 23:49:46 INFO - 'USER': 'cltbld', 23:49:46 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:46 INFO - '_': '/tools/buildbot/bin/python'} 23:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:49:46 INFO - Cleaning up... 23:49:46 INFO - Return code: 0 23:49:46 INFO - Running post-action listener: _resource_record_post_action 23:49:46 INFO - Running post-action listener: _start_resource_monitoring 23:49:46 INFO - Starting resource monitoring. 23:49:46 INFO - ##### 23:49:46 INFO - ##### Finished create-virtualenv step (success) 23:49:46 INFO - ##### 23:49:46 INFO - ##### 23:49:46 INFO - ##### Running install step. 23:49:46 INFO - ##### 23:49:46 INFO - Running pre-action listener: _resource_record_pre_action 23:49:46 INFO - Running main action method: install 23:49:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:49:46 INFO - Reading from file tmpfile_stdout 23:49:46 INFO - Detecting whether we're running mozinstall >=1.0... 23:49:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:49:46 INFO - Reading from file tmpfile_stdout 23:49:46 INFO - Output received: 23:49:46 INFO - Usage: mozinstall [options] installer 23:49:46 INFO - Options: 23:49:46 INFO - -h, --help show this help message and exit 23:49:46 INFO - -d DEST, --destination=DEST 23:49:46 INFO - Directory to install application into. [default: 23:49:46 INFO - "/builds/slave/test"] 23:49:46 INFO - --app=APP Application being installed. [default: firefox] 23:49:46 INFO - mkdir: /builds/slave/test/build/application 23:49:46 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'] 23:49:46 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 23:50:03 INFO - Reading from file tmpfile_stdout 23:50:03 INFO - Output received: 23:50:03 INFO - /builds/slave/test/build/application/firefox/firefox 23:50:03 INFO - Running post-action listener: _resource_record_post_action 23:50:03 INFO - ##### 23:50:03 INFO - ##### Finished install step (success) 23:50:03 INFO - ##### 23:50:03 INFO - ##### 23:50:03 INFO - ##### Running run-tests step. 23:50:03 INFO - ##### 23:50:03 INFO - Running pre-action listener: _resource_record_pre_action 23:50:03 INFO - Running main action method: run_tests 23:50:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:50:03 INFO - Python 2.7.3 23:50:03 INFO - Return code: 0 23:50:03 INFO - grabbing minidump binary from tooltool 23:50:03 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')]} 23:50:03 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 0x1b6e3b0>, '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 0x1b6f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:50:03 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 23:50:03 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 23:50:03 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 23:50:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:50:03 INFO - Return code: 0 23:50:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:50:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:50:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:50:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:50:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:50:03 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:50:03 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:50:03 INFO - Using env: {'DISPLAY': ':0', 23:50:03 INFO - 'HOME': '/home/cltbld', 23:50:03 INFO - 'LANG': 'en_US.UTF-8', 23:50:03 INFO - 'LANGUAGE': 'en_US:en', 23:50:03 INFO - 'LOGNAME': 'cltbld', 23:50:03 INFO - 'MAIL': '/var/mail/cltbld', 23:50:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:50:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:50:03 INFO - 'MOZ_NO_REMOTE': '1', 23:50:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:50:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:50:03 INFO - 'NO_EM_RESTART': '1', 23:50:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:50:03 INFO - 'PWD': '/builds/slave/test', 23:50:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:50:03 INFO - 'SHELL': '/bin/bash', 23:50:03 INFO - 'SHLVL': '1', 23:50:03 INFO - 'TERM': 'linux', 23:50:03 INFO - 'TMOUT': '86400', 23:50:03 INFO - 'USER': 'cltbld', 23:50:03 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852', 23:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:50:03 INFO - '_': '/tools/buildbot/bin/python'} 23:50:03 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:50:03 INFO - mozversion application_buildid: 20160702144223 23:50:03 INFO - mozversion application_changeset: b8b3b1c92085c1f8b74c13203289360932182da4 23:50:03 INFO - mozversion application_display_name: Nightly 23:50:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:50:03 INFO - mozversion application_name: Firefox 23:50:03 INFO - mozversion application_remotingname: firefox 23:50:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:50:03 INFO - mozversion application_vendor: Mozilla 23:50:03 INFO - mozversion application_version: 50.0a1 23:50:03 INFO - mozversion platform_buildid: 20160702144223 23:50:03 INFO - mozversion platform_changeset: b8b3b1c92085c1f8b74c13203289360932182da4 23:50:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:50:03 INFO - mozversion platform_version: 50.0a1 23:50:03 INFO - using testdate: 1467528603 23:50:03 INFO - actual date: 1467528603 23:50:03 INFO - starting webserver on 'localhost:46562' 23:50:03 INFO - SUITE-START | Running 2 tests 23:50:03 INFO - TEST-START | dromaeo_css 23:50:03 INFO - operating with platform_type : linux_ 23:50:03 INFO - Initialising browser for dromaeo_css test... 23:50:03 INFO - TEST-INFO | started process 3907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV1sVCk/profile http://localhost:46562/getInfo.html) 23:50:07 INFO - PROCESS | 3907 | __metrics Screen width/height:1600/1200 23:50:07 INFO - PROCESS | 3907 | colorDepth:24 23:50:07 INFO - PROCESS | 3907 | Browser inner width/height: 1024/697 23:50:07 INFO - PROCESS | 3907 | __metrics 23:50:08 INFO - PROCESS | 3907 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:50:09 INFO - TEST-INFO | 3907: exit 0 23:50:09 INFO - Browser initialized. 23:50:09 INFO - Running cycle 1/1 for dromaeo_css test... 23:50:09 INFO - TEST-INFO | started process 3990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV1sVCk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:50:21 INFO - PROCESS | 3990 | RSS: Main: 159223808 23:50:21 INFO - PROCESS | 3990 | 23:51:12 INFO - PROCESS | 3990 | 1467528672843 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] 23:53:25 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:46562/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46562/tests/dromaeo/cssquery-ext.html) 23:53:26 INFO - PROCESS | 3990 | RSS: Main: 261644288 23:53:26 INFO - PROCESS | 3990 | 23:56:29 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:46562/tests/dromaeo/cssquery-ext.html (next: http://localhost:46562/tests/dromaeo/cssquery-jquery.html) 23:56:30 INFO - PROCESS | 3990 | RSS: Main: 243998720 23:56:30 INFO - PROCESS | 3990 | 23:59:32 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:46562/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46562/tests/dromaeo/cssquery-mootools.html) 23:59:33 INFO - PROCESS | 3990 | RSS: Main: 243216384 23:59:33 INFO - PROCESS | 3990 | 00:02:35 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:46562/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46562/tests/dromaeo/cssquery-prototype.html) 00:02:36 INFO - PROCESS | 3990 | RSS: Main: 245080064 00:02:36 INFO - PROCESS | 3990 | 00:05:39 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:46562/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46562/tests/dromaeo/cssquery-yui.html) 00:05:39 INFO - PROCESS | 3990 | RSS: Main: 214806528 00:05:39 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | Cycle 1(1): loaded http://localhost:46562/tests/dromaeo/cssquery-yui.html (next: http://localhost:46562/tests/dromaeo/cssquery-dojo.html) 00:08:42 INFO - PROCESS | 3990 | RSS: Main: 295391232 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | __start_tp_report 00:08:42 INFO - PROCESS | 3990 | _x_x_mozilla_page_load 00:08:42 INFO - PROCESS | 3990 | _x_x_mozilla_page_load_details 00:08:42 INFO - PROCESS | 3990 | |i|pagename|runs| 00:08:42 INFO - PROCESS | 3990 | |0;dojo.html;2659.5478768609337;2665.920022399329;2695.851728154951;2700.6219129321885;2703.9459210815785;4631.050634619904;4708.176069187887;4764.4759076501605;4845.85462436127;4861.927071093932;4685.9062818743605;4734.952650473494;4738.123447162277;4756.857294281169;4769.236922092466;4385;4397.868063958086;4417.3815665806815;4436.578525040116;4437.600615944564;4410.933835992462;4467.687261891669;4495.303227999665;4514.729116253019;4519.073589914076;4433.733975961452;4464.709793863389;4476;4478.664100192483;4501.662375321847;4303.182395344875;4303.462067241595;4322.610965013164;4332.740035597874;4368.388425620416;4295.914081718348;4297.677674174418;4341.739495630272;4359.237133501656;4359.585839345289;4752.928706069411;4762.238041913277;4779.713217206979;4797.520247975174;4800.855974320776;4957.5786058185095;5103.413107492649;5152.433232344439;5156.252343410185;5249.081410753141;5207.114790485626;5243.947560524422;5253.3170687810725;5265.921011184835;5273.34083239595;4668.463126740468;4840.15297322967;4881.4142302923165;4881.9267710984;4890.046440944053;5085.160948443466;5141.280220769096;5147.073526765218;5166.147585648326;5202.765875535534;4970.776315065831;5011.84964451067;5019.698818070928;5022.573081288058;5024;5017.297578339036;5090.211017292326;5114.079465696211;5140.768665410065;5174.7930082796265;5093.821716239914;5154.484551544891;5180.585553155738;5192.1952097424955;5195.402528709171;5107.080725469451;5126.256692779526;5127.538521533005;5129.358830146231;5179.249008893689;4165.187788381297;4320.848770293024;4330.848420305338;4331.826726930951;4378.190034843564;3702.6852717518773;3733.029412352808;3772.490713753663;3773.698104151716;3820.1595648957186;4450.933236001462;4452.532484089189;4466.798994045275;4472.731636101845;4500.842470513517;5127.974360128175;5149.897002060013;5153.896922061538;5160.509751573594;5200.947990520046;4248.936265956013;4362.607365337135;4405.31717583775;4452.48796388413;4468.195724769573;4600.5629465200755;4603.516630753723;4609.2394754865745;4618.168729628632;4640.489546149921;4657.045305712389;4835.840126867692;4854.708717476963;4855.417349918032;4902.681325713817;5009.7244651544515;5047.899042019212;5061.848144555669;5081.034603425337;5096.541311282002;4781.928271076006;4808.014357056865;4820.060088282751;4826.0830442215865;4858.58702010333;4905.7792399342825;5014.047331007243;5023.1711767557945;5023.2716256143385;5067.366579177603;5131.640785145004;5140.871478212925;5151.871203219949;5175.171972484384;5223.503767142115;4992.925106123484;5003.424606170337;5024.860779634166;5026.421961474477;5066.771995260148;4848.951510484992;4909.042736666316;4943.876403089807;5009.77456014473;5023.924641130459;4906.411230652345;4916.164252077227;4932.038252540864;4949.678270912379;4962.950370496249;5006.3992320921725;5017.473165317735;5017.54842064216;5018.42288136869;5073.188289873602;4797.928031079467;4821.4696383397095;4843.418789745253;4892.633052521004;4901.681390709592 00:08:42 INFO - PROCESS | 3990 | |1;ext.html;566.4222493057082;569.5728203847115;576.5070864410928;581.2879222951882;588.2441063233746;28290.151295461143;40525.39211911824;41706.37440438392;41721.374179387334;44977.55022449775;41702.58297417025;41816.16367672645;41978.58021419785;41998.99999999993;42056;7540.924590754077;7821.491603045798;7962.044554653449;7967.840643187133;7968.721094761684;7489;7717.18969508202;7718.768436946901;10060.496975151213;10343.172546196323;10401.583936642524;10434.373937563732;10435.321704089249;10452.320599161068;10456;10297.330673506236;10307.072363487323;10354.119899808526;10370.533326000348;10376;7585.469017168801;7809.062912450486;7814.023247094114;10043.598256069748;10447.634332798316;39896.40155397671;40229;40260;40313.19373612541;40322.59677403218;3077.5999120114466;3085.1207405889213;3225.822579758112;3291.358185153872;3766.114962983697;30381.936632217767;30770.615232324428;31146.688533114833;31655.525167122512;32217.677823221704;3590.9102272443397;3614.1145419372424;3653.204264061416;3665.4318580620047;3694.538182727159;258886;260780;261431;262744;264024;46046.5395346049;53355;53686.19470707943;53760.73119634377;53895.19157212645;53715.46284537105;55233.447665523636;55443;61398.69300653468;61490.38509614936;13793.379297931617;13861.4455421782;13888.58334249954;13907.165570065828;13911.582652520441;10102.19182465401;11288.0969522438;11391.544338226544;11409.315441073562;11416.31502109876;15022.624434389229;15445.691086178214;15447.227638618024;15817.339179386228;15943.44197953066;5410.269613602192;5462.3445186577865;5551.25058117149;5681.147827825859;5732.197492351074;860.2816648098783;904.968326108583;970.2626004236831;974.1086905481546;1034.462079718557;27635;28921.56617650737;28967;28972.27569310784;29394;1004.6935684616116;1014.5282443663732;1023.048564834696;1027.8355463125863;1032.5972870580479;15402.152881591395;15419.229038548028;15424.151671658166;15468.381264749509;15599.532014039596;114786.42606786912;115254;115332.42333788445;115989.42005290088;116067.41966290283;49701.50298497041;50617.73455663638;50746.23880641793;51038.744806276474;51313;1083.772407794372;1140.503880811833;1150.8158694608821;1203.3802591665299;1210.346412937004;28398.29004274827;29891.252718682208;30314;30898.53652195264;31184.376312474076;44116.77941610271;44836.32745508885;44973.32540011968;44985;45017.774911125234;362302;372873;373887;374382;374432;33954.66045339515;34800.82599586986;37577.248455031295;42850;44041;42463.787681061396;42942.35586466268;43065.78467107644;43597.47408840675;43724;37344.253114938096;37681.81159094187;37729.2454150921;38281;38399;37932.24135517219;38029.80985095168;38076.61923380731;38190.618093819816;38555.42166867555;37126.6287337134;37219.441708374936;37428.81285593555;37833.243335132596;37964;32782.67217327796;33032;33376.83311583426;33997.830010849786;34041;25504.489910202064;26002.349941250865;26368;26388.208353749418;26667 00:08:42 INFO - PROCESS | 3990 | |2;jquery.html;353.961064282929;373.17528262539787;378.990525236869;384.6614978818639;400.4373854734098;13269.535566255183;14268.001239913197;17244.91377543112;17322.393716219936;17340;15360.769588456182;17150.399736009243;17378.21798019089;17421.390251341207;17447.389341373055;5139.845804625857;5335.493128152839;5369.355677318727;5372.838814835562;5375.516203541681;3853.7880416577077;5284.075286824809;5827.096799995987;6136.202293701813;6157.661328626924;6181.721822517998;6193.504519638418;6213.285472170691;6216.316205217422;6217.440430361266;5266.973665131669;5552.750126244308;5553.416891226403;5652.773889044433;5654.660720356792;3438.2435864109857;5021.6735912165595;5248.081585722522;5254.395744489356;5447.509724124848;21151.682724759143;21676.02457889383;21892.12431502738;22154.22460213885;22154.778452215593;1950.1419375474745;1995.8802471851736;1997.490639886834;2027.787082356346;2032.8576999610034;9548.474833884133;9901.603935842557;10024.19806415485;10157.54291056897;13168.485624153249;1752.6582316448298;1770.849477794389;1790.239148361941;1803.4409333106778;1853.7960824309316;69357.65321173413;76288.23711762922;76509.61745191239;77086.6145669268;77716;9652.179564736936;9863.704088877344;9977.650782222732;10109.494525273707;10111.7472063199;13452.058355915187;13471.865281347062;13514.797278040838;13711.657208569866;13717.245551494563;7406.814829629302;7418.295261950106;7579.848403031909;7653.655585498666;7661.578613176329;7411.993190112678;7446.85106297871;7508.324250817453;7632.389408847279;7632.885506717404;4154.750714957112;4216.8313267469575;4239.894002649897;4360.106178233455;4614.377059097046;1694.5085925081835;1699.5836020175136;1721.0878234535717;1760.287083731091;1760.7887053553657;842.1452225990624;842.616609442702;849.180540778152;868.9217970382696;942.7784470649392;17668.734968975477;17713.822861771216;17928.372506962456;18349.7247541287;18431.907840460713;822.2392348303376;837.3719710217337;885.8759773631697;896.5606852642159;918.8989211186764;9707.077827606367;9848.113669769611;10175.045959555544;10190.133838623651;10192;437971;448055;449801;451124;451528;50659.24011139836;67409.66295168492;72553.6372318135;82427;84272;832.1221111727307;882.6513527156737;891.157855826238;896.7847716548112;902.9683961061463;15344.769828452805;15887.443939462064;16040.358385664442;16042.839571604134;16529.834701652828;40821.958902055376;40976.79511602542;41057;41607.58392416158;41706;53629.7318513405;56121.07152499286;56240.156397654886;56870;57997.420025799205;7844.294013538809;8027.2775450209765;8028.518288902685;8204.179582041748;8246.257836794717;11945.604923640634;12494.187877787916;12555.74888502243;12752.107352485236;13014.4143513544;10861.34831910088;10957.287776294515;10976.23166378346;11143.33140011602;11461.255018423775;10958.397288149228;11006.614768483065;11022.614208502664;11090.112790976542;11264.94367528157;11034.724131896766;11228.606998755004;11366.374849383363;11454.312741235553;11610.477528511406;7775.377969762519;8030.959845200737;8045;8293.668253270043;8376;8292.04641466239;10419.635312764018;11030.50362733663;11060.281081729661;11282.548698051985 00:08:42 INFO - PROCESS | 3990 | |3;mootools.html;1285.8585555588877;1370.451819272291;1432.0619993903993;1509.675419784746;1718.6390857919837;6979.511434199601;7632.3894088472925;7872.842543149133;7878.842423151534;8089.838203235931;7791.766247012599;7956.840863182747;7960.08459027212;7964.721234756785;7968.920310796891;2229.665550167472;2246.70792796937;2253.1663284584642;2255.537614788964;2260.864348139117;2208.436848603612;2237.9552408951813;2266.875321857297;2268.6710426988075;2269.2851751698245;2234.084025549525;2258.5144193998285;2266.6826644269813;2273.9886300568473;2277.977220227793;2176.053416763697;2206.702095217141;2215.3796936857707;2216.612092883739;2218.301235110943;2109.947251318714;2115.471132216946;2118.9152433902627;2119.078200982562;2120.74551053873;7932.682692692285;7988.121306656263;8011.319037881787;8067.2739453448885;8292.502449853027;4668.36977008103;4769.523047695237;4777.593904518086;4781.450133234688;4781.928271075936;2893.23329317731;2983.6270466191727;3014.6533148687963;3021.289996850738;3026.031669865644;952.0336858089045;962.5957098018779;970.8592254123113;1002.5388321372178;1004.0210794475402;11251.54993800247;11343.78796134754;11346.489407976578;11363.488643011084;11388.088952883667;4214.4099826024385;4220.240356735817;4376.824927002948;4386.934195987063;4403.031333106712;8812.206901378906;8868;8875.112488751203;8879.023307436177;8886.733397997941;4723.5984941279685;4745.738984355894;4751.049790042005;4751.287306903992;4752.5485078917445;3755.868544600926;3759.041444431707;3770.9245815083545;3886.8833934981994;4366.366876802846;3533.8763143290375;3590.551181102362;3674.063113905937;3675.081229692577;3702.426123950792;1093.8359246112987;1109.5949978257877;1121.6130434999911;1124.145649306533;1131.1685910855515;723.9022731931227;786.0253285925411;846.2848892685671;847.0216899481109;852.2117041736369;5123.359580052494;5392.811251606175;5464.890702186013;5504.256925315031;5508.807191748269;833.7540425574471;839.47532792005;846.3609974469236;867.1242045534055;875.8598624220094;3529.9294014120087;3723.162288485069;3779.055236190952;3797.2974999625158;3813.275477659236;9825.705228843146;10183.439910804977;10287.794244115075;10325.793484130276;10331.225158113171;10205.744856378502;10214.285000049926;10226;10232.02795734404;10238.948805255926;7219.675114619855;7559.962200188964;7609;7667.3099421052375;7701.3068823806125;5645.4072322405555;5702.173184888251;5721.342045664635;5756.769729210785;5893.233879595625;8858.069902660385;9177.724668259963;9197.77005574866;9425.340226184102;9480.194183494481;9192.356535042483;9486.905130948879;9504.572294246915;9512.714618561455;9656.37233579815;6926.722931082902;7016.36852683261;7038.085048943604;7044.436445084279;7050.294970502909;7476.700931962878;7588.696452142073;7653.502522336031;7656.617169141409;7664.73173438927;6896.655167241517;6903.1371078615175;6983.3714965653335;6994.825129371807;7054.894176587252;6842.24735279109;6932.376086152271;6949.061876646588;7021.789346319619;7046.330598593126;6126.754929802758;6145.84635384119;6158.568900176944;6164.907526387197;6187.195664563578;4480.9103817924115;4558.088382323483;4625.398698169217;4654.045920586339;4718.622510199244;4642.558956899089;4729.10147072069;4734.100520901018;4750.168720473972;4847.345608342828 00:08:42 INFO - PROCESS | 3990 | |4;prototype.html;287.26746795671033;296.46192161227356;298;304.4428695487258;308.99382012359746;4807.375041244638;4833.250846118857;4833.734144622049;4845.7819398127085;4848.418189817223;4778.7062210139065;4790.473047964721;4791.3052607372;4809.254565542338;4813.951860481394;3626.727995400349;3708.128589781399;3734.495843061179;3762.1159027628487;3763;3723.515942927416;3796.335641262782;3804.4293355996683;3808.7143464240153;3816.1985982943615;3718.498002769632;3731.477593136963;3742.8877133685896;3759.492468516756;3775.1505911169907;3553.3603951288756;3654.5979942206336;3760.2291530236507;3766.7928263945473;3803.7337386382965;3318.5188147718445;3320.701136897667;3329;3331.68349006844;3347.5313456116164;4642.442906851199;4645.535446455361;4645.651576131803;4654.348391225231;4733.124371991193;4200.831966721327;4273.764942928137;4325.502567204749;4326.09152078064;4329.913401731979;4948.901021979576;4960.925586116211;4963.627727920384;4982.750862456899;5001.3998320201445;3655.4151335786146;3662.08447888028;3668.1196512836727;3669.174435751961;3669.853205871762;5134.743262836843;5218.895622087575;5245.239440281138;5249.212618107276;5250.501202385767;3713.20166164276;3737.252549490113;3738.738288319846;3746.456763769238;3753.0992561784974;3800.3349413852466;3805.1438426354403;3806.238752249561;3809.5047643806683;3810.828512716879;4302.569743025735;4305.418768466279;4305.612494875476;4306.913861722811;4314.158739045856;4935.654504184673;4990.8253211137435;5016.54851063406;5019.0965626187635;5087.16061849798;1294.3140135728079;1301.1412467771227;1303.589369348652;1310.1353106949368;1315.697389600378;811.0834756724981;853.5007020892847;853.5945425922637;855.6064210463256;862.6980556805194;4352.7823608819435;4465.4864690561;4465.620422264144;4467.709598876063;4467.754273514923;5084.7966081356335;5097.7706003229205;5099.64302498829;5138;5141.203113517411;4413.426254586883;4429;4433.844815431445;4434.977825110918;4476.328550717353;2562.93592660185;2755.655543057118;2757.310672331917;2761.8480983546096;2765.612814206013;4876.951455437098;4895.412550493964;4900.142475066849;4907.1657818171;4925.1873440881855;5021.196608542616;5249.482846492859;5264.157734762496;5270.815521456807;5287.20691896211;4841.733704646137;4878.853634391045;4895.143349913751;4904.779284932187;4911.729854857946;5125;5201.089809283436;5210.218467229794;5222.425533191422;5238.816641417532;4882.926756098733;5058.595312375075;5125.974370128126;5174.6119041071315;5175.92236116451;4852.8544143675745;4873.829415970424;4873.853784386474;4884.8046078157895;4888.144574699342;3767.321882061255;3790.4882840816135;3812.714046446472;3818.3890577507464;3838.136419305634;4987.825426110068;5189;5193.792248310026;5198;5227.085260079547;4998.850034498971;5015.473375295542;5019.799208031784;5037.143685573388;5053.31780209667;5031.767217031851;5051.520105589963;5077.466865979019;5122.282880396673;5132.5893928486375;4894.95105048945;4983.775730092082;4993.101241776515;5013.974930125326;5044.319016932667;4846.17615005457;4951.356323677899;5069.7465126744255;5081.060003899291;5089.465606111305;4944.579710724629;4953.356063711839;4960.404751429852;5006.6495345325475;5029.974850125726 00:08:42 INFO - PROCESS | 3990 | |5;yui.html;660.1978596005852;678.1658559971238;681.3220845258962;737.5058710663855;757.8938948547203;13744.725105497884;14806.111633302009;14810.555683329518;14829.777553336682;14858.628534286649;13358.732825343488;13584.932075339635;13828.792568111487;13830.308484575782;13836.239006854645;628.7327885648604;768.2471178245323;774.8140446292891;779.7426849139798;792.1603100713234;781.86708259596;786.9803254918615;792.0772300270178;792.5086446403238;792.5759718550577;836.506461187899;837.2966707965307;841.6927821345196;843.2284459719349;843.28320927212;652.5203975078314;801.2548330053048;823.3289868756974;826.6775957376628;839.2656425627575;597;601.4947444146937;602.2441835496468;603.3574243430733;603.643850128422;13504.864951350459;13528.526501572496;13530.93234533831;13547.119437236646;13548.187108773505;12546.81179782304;12819.615411537668;12831.230126192366;13087.41106650203;13172.077954543185;11882.821757673642;11951;11978.940105299418;12034.879651203464;12296.569620063256;4140.544540100586;4153.501579810412;4161.542230354658;4161.875143745662;4165.12532368203;63460.36539634591;63660.68169659168;63927.68036159836;64673.3532664672;65460.67269663669;10277.434741089312;10403.94798026005;10404.803447603472;10425.11386532138;10469.68590942273;18455;18517.07414629263;18688;18698.34555790541;18713.81286187148;7055.435565154775;7106.538075025107;7141.642917854087;7145.857082858418;7317.487775855746;3835.808209589565;3913.8043097844993;3949.664278536299;3986.362182050858;3992.840286388512;2863.9427211455654;2953.6307961504813;2954.0251716933362;2954.896578619738;2959.215807810932;503.94204666463827;503.9571636410946;517.244822559068;518.2899427783885;518.9455107213688;481.5184815184815;499.6202885806813;542.4548328929401;599.0475144520234;624.2321944008804;8164.428490005643;8195.71315003972;8319.916800831914;8325;8434.493930364199;531.1315998342737;546.778554685353;547.6905548365161;564.4214679952998;606.6663335165641;3820.6561409472724;3891.7664940103687;3910.7262491625884;3926.842926282917;4023.879283621496;77118.22881771222;79655;79913.20086799173;92808;105868.47065764776;74838;75900.62049689826;76944;83119;83941.58029209815;57825.710871446216;71543.6422817893;73914;74618.96477375805;75228.62385688037;7934.523928564305;8112.59437028158;8207.425480216327;8227.177282271725;8365.372597055124;30349.544756829106;33170.66829331676;39274.60725392824;40419.3937090938;43327.35008974931;56824.43175568357;57226.42773572211;58692.70653646704;59629;60245.397546025735;11544.769104618028;11687.59369848129;11836.349000805038;11980.880191198325;12289.385530723606;13460.25968571738;13673.085767992688;13745.587632371045;14394.496192633627;14424.711505769616;12471.189372690746;12509.687257818263;12871.485140594647;13161.341932903126;13492.392842321922;11610.303381797119;12717.618471445872;12956.28740419286;13069.934650326688;13166.47334106625;11697.824532631847;11732.237404568676;11735.23720958135;12226.510939562319;12566.994640428926;9831.213502919893;10111.03945125212;10247.38515689061;10260.435676037889;10417.374957502574;9718.659846905324;10203.540840662337;10591.417472038804;10709.303895246783;10731.517081731497 00:08:42 INFO - PROCESS | 3990 | __end_tp_report 00:08:42 INFO - PROCESS | 3990 | __start_cc_report 00:08:42 INFO - PROCESS | 3990 | _x_x_mozilla_cycle_collect,964 00:08:42 INFO - PROCESS | 3990 | __end_cc_report 00:08:42 INFO - PROCESS | 3990 | __startTimestamp1467529722954__endTimestamp 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | ------- Summary: start ------- 00:08:42 INFO - PROCESS | 3990 | Number of tests: 6 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | [#0] dojo.html Cycles:165 Average:4730.38 Median:4855.42 stddev:498.70 (10.3%) stddev-sans-first:473.03 00:08:42 INFO - PROCESS | 3990 | Values: 2659.5 2665.9 2695.9 2700.6 2703.9 4631.1 4708.2 4764.5 4845.9 4861.9 4685.9 4735.0 4738.1 4756.9 4769.2 4385.0 4397.9 4417.4 4436.6 4437.6 4410.9 4467.7 4495.3 4514.7 4519.1 4433.7 4464.7 4476.0 4478.7 4501.7 4303.2 4303.5 4322.6 4332.7 4368.4 4295.9 4297.7 4341.7 4359.2 4359.6 4752.9 4762.2 4779.7 4797.5 4800.9 4957.6 5103.4 5152.4 5156.3 5249.1 5207.1 5243.9 5253.3 5265.9 5273.3 4668.5 4840.2 4881.4 4881.9 4890.0 5085.2 5141.3 5147.1 5166.1 5202.8 4970.8 5011.8 5019.7 5022.6 5024.0 5017.3 5090.2 5114.1 5140.8 5174.8 5093.8 5154.5 5180.6 5192.2 5195.4 5107.1 5126.3 5127.5 5129.4 5179.2 4165.2 4320.8 4330.8 4331.8 4378.2 3702.7 3733.0 3772.5 3773.7 3820.2 4450.9 4452.5 4466.8 4472.7 4500.8 5128.0 5149.9 5153.9 5160.5 5200.9 4248.9 4362.6 4405.3 4452.5 4468.2 4600.6 4603.5 4609.2 4618.2 4640.5 4657.0 4835.8 4854.7 4855.4 4902.7 5009.7 5047.9 5061.8 5081.0 5096.5 4781.9 4808.0 4820.1 4826.1 4858.6 4905.8 5014.0 5023.2 5023.3 5067.4 5131.6 5140.9 5151.9 5175.2 5223.5 4992.9 5003.4 5024.9 5026.4 5066.8 4849.0 4909.0 4943.9 5009.8 5023.9 4906.4 4916.2 4932.0 4949.7 4963.0 5006.4 5017.5 5017.5 5018.4 5073.2 4797.9 4821.5 4843.4 4892.6 4901.7 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | [#1] ext.html Cycles:180 Average:42740.04 Median:3659.32 stddev:71524.85 (1954.6%) stddev-sans-first:71655.40 00:08:42 INFO - PROCESS | 3990 | Values: 566.4 569.6 576.5 581.3 588.2 28290.2 40525.4 41706.4 41721.4 44977.6 41702.6 41816.2 41978.6 41999.0 42056.0 7540.9 7821.5 7962.0 7967.8 7968.7 7489.0 7717.2 7718.8 10060.5 10343.2 10401.6 10434.4 10435.3 10452.3 10456.0 10297.3 10307.1 10354.1 10370.5 10376.0 7585.5 7809.1 7814.0 10043.6 10447.6 39896.4 40229.0 40260.0 40313.2 40322.6 3077.6 3085.1 3225.8 3291.4 3766.1 30381.9 30770.6 31146.7 31655.5 32217.7 3590.9 3614.1 3653.2 3665.4 3694.5 258886.0 260780.0 261431.0 262744.0 264024.0 46046.5 53355.0 53686.2 53760.7 53895.2 53715.5 55233.4 55443.0 61398.7 61490.4 13793.4 13861.4 13888.6 13907.2 13911.6 10102.2 11288.1 11391.5 11409.3 11416.3 15022.6 15445.7 15447.2 15817.3 15943.4 5410.3 5462.3 5551.3 5681.1 5732.2 860.3 905.0 970.3 974.1 1034.5 27635.0 28921.6 28967.0 28972.3 29394.0 1004.7 1014.5 1023.0 1027.8 1032.6 15402.2 15419.2 15424.2 15468.4 15599.5 114786.4 115254.0 115332.4 115989.4 116067.4 49701.5 50617.7 50746.2 51038.7 51313.0 1083.8 1140.5 1150.8 1203.4 1210.3 28398.3 29891.3 30314.0 30898.5 31184.4 44116.8 44836.3 44973.3 44985.0 45017.8 362302.0 372873.0 373887.0 374382.0 374432.0 33954.7 34800.8 37577.2 42850.0 44041.0 42463.8 42942.4 43065.8 43597.5 43724.0 37344.3 37681.8 37729.2 38281.0 38399.0 37932.2 38029.8 38076.6 38190.6 38555.4 37126.6 37219.4 37428.8 37833.2 37964.0 32782.7 33032.0 33376.8 33997.8 34041.0 25504.5 26002.3 26368.0 26388.2 26667.0 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | [#2] jquery.html Cycles:180 Average:26461.80 Median:4228.36 stddev:73655.34 (1741.9%) stddev-sans-first:73835.87 00:08:42 INFO - PROCESS | 3990 | Values: 354.0 373.2 379.0 384.7 400.4 13269.5 14268.0 17244.9 17322.4 17340.0 15360.8 17150.4 17378.2 17421.4 17447.4 5139.8 5335.5 5369.4 5372.8 5375.5 3853.8 5284.1 5827.1 6136.2 6157.7 6181.7 6193.5 6213.3 6216.3 6217.4 5267.0 5552.8 5553.4 5652.8 5654.7 3438.2 5021.7 5248.1 5254.4 5447.5 21151.7 21676.0 21892.1 22154.2 22154.8 1950.1 1995.9 1997.5 2027.8 2032.9 9548.5 9901.6 10024.2 10157.5 13168.5 1752.7 1770.8 1790.2 1803.4 1853.8 69357.7 76288.2 76509.6 77086.6 77716.0 9652.2 9863.7 9977.7 10109.5 10111.7 13452.1 13471.9 13514.8 13711.7 13717.2 7406.8 7418.3 7579.8 7653.7 7661.6 7412.0 7446.9 7508.3 7632.4 7632.9 4154.8 4216.8 4239.9 4360.1 4614.4 1694.5 1699.6 1721.1 1760.3 1760.8 842.1 842.6 849.2 868.9 942.8 17668.7 17713.8 17928.4 18349.7 18431.9 822.2 837.4 885.9 896.6 918.9 9707.1 9848.1 10175.0 10190.1 10192.0 437971.0 448055.0 449801.0 451124.0 451528.0 50659.2 67409.7 72553.6 82427.0 84272.0 832.1 882.7 891.2 896.8 903.0 15344.8 15887.4 16040.4 16042.8 16529.8 40822.0 40976.8 41057.0 41607.6 41706.0 53629.7 56121.1 56240.2 56870.0 57997.4 7844.3 8027.3 8028.5 8204.2 8246.3 11945.6 12494.2 12555.7 12752.1 13014.4 10861.3 10957.3 10976.2 11143.3 11461.3 10958.4 11006.6 11022.6 11090.1 11264.9 11034.7 11228.6 11366.4 11454.3 11610.5 7775.4 8031.0 8045.0 8293.7 8376.0 8292.0 10419.6 11030.5 11060.3 11282.5 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | [#3] mootools.html Cycles:180 Average:5281.30 Median:4761.04 stddev:3007.90 (63.2%) stddev-sans-first:3001.35 00:08:42 INFO - PROCESS | 3990 | Values: 1285.9 1370.5 1432.1 1509.7 1718.6 6979.5 7632.4 7872.8 7878.8 8089.8 7791.8 7956.8 7960.1 7964.7 7968.9 2229.7 2246.7 2253.2 2255.5 2260.9 2208.4 2238.0 2266.9 2268.7 2269.3 2234.1 2258.5 2266.7 2274.0 2278.0 2176.1 2206.7 2215.4 2216.6 2218.3 2109.9 2115.5 2118.9 2119.1 2120.7 7932.7 7988.1 8011.3 8067.3 8292.5 4668.4 4769.5 4777.6 4781.5 4781.9 2893.2 2983.6 3014.7 3021.3 3026.0 952.0 962.6 970.9 1002.5 1004.0 11251.5 11343.8 11346.5 11363.5 11388.1 4214.4 4220.2 4376.8 4386.9 4403.0 8812.2 8868.0 8875.1 8879.0 8886.7 4723.6 4745.7 4751.0 4751.3 4752.5 3755.9 3759.0 3770.9 3886.9 4366.4 3533.9 3590.6 3674.1 3675.1 3702.4 1093.8 1109.6 1121.6 1124.1 1131.2 723.9 786.0 846.3 847.0 852.2 5123.4 5392.8 5464.9 5504.3 5508.8 833.8 839.5 846.4 867.1 875.9 3529.9 3723.2 3779.1 3797.3 3813.3 9825.7 10183.4 10287.8 10325.8 10331.2 10205.7 10214.3 10226.0 10232.0 10238.9 7219.7 7560.0 7609.0 7667.3 7701.3 5645.4 5702.2 5721.3 5756.8 5893.2 8858.1 9177.7 9197.8 9425.3 9480.2 9192.4 9486.9 9504.6 9512.7 9656.4 6926.7 7016.4 7038.1 7044.4 7050.3 7476.7 7588.7 7653.5 7656.6 7664.7 6896.7 6903.1 6983.4 6994.8 7054.9 6842.2 6932.4 6949.1 7021.8 7046.3 6126.8 6145.8 6158.6 6164.9 6187.2 4480.9 4558.1 4625.4 4654.0 4718.6 4642.6 4729.1 4734.1 4750.2 4847.3 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | [#4] prototype.html Cycles:180 Average:4193.59 Median:4823.60 stddev:1208.05 (25.0%) stddev-sans-first:1175.32 00:08:42 INFO - PROCESS | 3990 | Values: 287.3 296.5 298.0 304.4 309.0 4807.4 4833.3 4833.7 4845.8 4848.4 4778.7 4790.5 4791.3 4809.3 4814.0 3626.7 3708.1 3734.5 3762.1 3763.0 3723.5 3796.3 3804.4 3808.7 3816.2 3718.5 3731.5 3742.9 3759.5 3775.2 3553.4 3654.6 3760.2 3766.8 3803.7 3318.5 3320.7 3329.0 3331.7 3347.5 4642.4 4645.5 4645.7 4654.3 4733.1 4200.8 4273.8 4325.5 4326.1 4329.9 4948.9 4960.9 4963.6 4982.8 5001.4 3655.4 3662.1 3668.1 3669.2 3669.9 5134.7 5218.9 5245.2 5249.2 5250.5 3713.2 3737.3 3738.7 3746.5 3753.1 3800.3 3805.1 3806.2 3809.5 3810.8 4302.6 4305.4 4305.6 4306.9 4314.2 4935.7 4990.8 5016.5 5019.1 5087.2 1294.3 1301.1 1303.6 1310.1 1315.7 811.1 853.5 853.6 855.6 862.7 4352.8 4465.5 4465.6 4467.7 4467.8 5084.8 5097.8 5099.6 5138.0 5141.2 4413.4 4429.0 4433.8 4435.0 4476.3 2562.9 2755.7 2757.3 2761.8 2765.6 4877.0 4895.4 4900.1 4907.2 4925.2 5021.2 5249.5 5264.2 5270.8 5287.2 4841.7 4878.9 4895.1 4904.8 4911.7 5125.0 5201.1 5210.2 5222.4 5238.8 4882.9 5058.6 5126.0 5174.6 5175.9 4852.9 4873.8 4873.9 4884.8 4888.1 3767.3 3790.5 3812.7 3818.4 3838.1 4987.8 5189.0 5193.8 5198.0 5227.1 4998.9 5015.5 5019.8 5037.1 5053.3 5031.8 5051.5 5077.5 5122.3 5132.6 4895.0 4983.8 4993.1 5014.0 5044.3 4846.2 4951.4 5069.7 5081.1 5089.5 4944.6 4953.4 4960.4 5006.6 5030.0 00:08:42 INFO - PROCESS | 3990 | 00:08:42 INFO - PROCESS | 3990 | [#5] yui.html Cycles:180 Average:17252.58 Median:4161.71 stddev:23545.79 (565.8%) stddev-sans-first:23578.88 00:08:42 INFO - PROCESS | 3990 | Values: 660.2 678.2 681.3 737.5 757.9 13744.7 14806.1 14810.6 14829.8 14858.6 13358.7 13584.9 13828.8 13830.3 13836.2 628.7 768.2 774.8 779.7 792.2 781.9 787.0 792.1 792.5 792.6 836.5 837.3 841.7 843.2 843.3 652.5 801.3 823.3 826.7 839.3 597.0 601.5 602.2 603.4 603.6 13504.9 13528.5 13530.9 13547.1 13548.2 12546.8 12819.6 12831.2 13087.4 13172.1 11882.8 11951.0 11978.9 12034.9 12296.6 4140.5 4153.5 4161.5 4161.9 4165.1 63460.4 63660.7 63927.7 64673.4 65460.7 10277.4 10403.9 10404.8 10425.1 10469.7 18455.0 18517.1 18688.0 18698.3 18713.8 7055.4 7106.5 7141.6 7145.9 7317.5 3835.8 3913.8 3949.7 3986.4 3992.8 2863.9 2953.6 2954.0 2954.9 2959.2 503.9 504.0 517.2 518.3 518.9 481.5 499.6 542.5 599.0 624.2 8164.4 8195.7 8319.9 8325.0 8434.5 531.1 546.8 547.7 564.4 606.7 3820.7 3891.8 3910.7 3926.8 4023.9 77118.2 79655.0 79913.2 92808.0 105868.5 74838.0 75900.6 76944.0 83119.0 83941.6 57825.7 71543.6 73914.0 74619.0 75228.6 7934.5 8112.6 8207.4 8227.2 8365.4 30349.5 33170.7 39274.6 40419.4 43327.4 56824.4 57226.4 58692.7 59629.0 60245.4 11544.8 11687.6 11836.3 11980.9 12289.4 13460.3 13673.1 13745.6 14394.5 14424.7 12471.2 12509.7 12871.5 13161.3 13492.4 11610.3 12717.6 12956.3 13069.9 13166.5 11697.8 11732.2 11735.2 12226.5 12567.0 9831.2 10111.0 10247.4 10260.4 10417.4 9718.7 10203.5 10591.4 10709.3 10731.5 00:08:42 INFO - PROCESS | 3990 | -------- Summary: end -------- 00:08:42 INFO - PROCESS | 3990 | 00:08:43 INFO - TEST-INFO | 3990: exit 0 00:08:43 INFO - TEST-OK | dromaeo_css | took 1120270ms 00:08:43 INFO - TEST-START | kraken 00:08:43 INFO - operating with platform_type : linux_ 00:08:43 INFO - Initialising browser for kraken test... 00:08:43 INFO - TEST-INFO | started process 4177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp62vcBF/profile http://localhost:46562/getInfo.html) 00:08:47 INFO - PROCESS | 4177 | __metrics Screen width/height:1600/1200 00:08:47 INFO - PROCESS | 4177 | colorDepth:24 00:08:47 INFO - PROCESS | 4177 | Browser inner width/height: 1024/697 00:08:47 INFO - PROCESS | 4177 | __metrics 00:08:49 INFO - PROCESS | 4177 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:08:49 INFO - TEST-INFO | 4177: exit 0 00:08:49 INFO - Browser initialized. 00:08:49 INFO - Running cycle 1/1 for kraken test... 00:08:49 INFO - TEST-INFO | started process 4258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp62vcBF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:09:01 INFO - PROCESS | 4258 | RSS: Main: 158519296 00:09:01 INFO - PROCESS | 4258 | 00:09:04 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46562/tests/kraken/driver.html?testName=audio-beat-detection) 00:09:05 INFO - PROCESS | 4258 | RSS: Main: 216842240 00:09:05 INFO - PROCESS | 4258 | 00:09:08 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46562/tests/kraken/driver.html?testName=audio-dft) 00:09:08 INFO - PROCESS | 4258 | RSS: Main: 203800576 00:09:08 INFO - PROCESS | 4258 | 00:09:12 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46562/tests/kraken/driver.html?testName=audio-fft) 00:09:12 INFO - PROCESS | 4258 | RSS: Main: 271077376 00:09:12 INFO - PROCESS | 4258 | 00:09:14 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46562/tests/kraken/driver.html?testName=audio-oscillator) 00:09:15 INFO - PROCESS | 4258 | RSS: Main: 181321728 00:09:15 INFO - PROCESS | 4258 | 00:09:17 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46562/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:09:18 INFO - PROCESS | 4258 | RSS: Main: 185577472 00:09:18 INFO - PROCESS | 4258 | 00:09:21 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46562/tests/kraken/driver.html?testName=imaging-darkroom) 00:09:22 INFO - PROCESS | 4258 | RSS: Main: 222351360 00:09:22 INFO - PROCESS | 4258 | 00:09:25 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46562/tests/kraken/driver.html?testName=imaging-desaturate) 00:09:26 INFO - PROCESS | 4258 | RSS: Main: 217473024 00:09:26 INFO - PROCESS | 4258 | 00:09:29 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46562/tests/kraken/driver.html?testName=json-parse-financial) 00:09:29 INFO - PROCESS | 4258 | RSS: Main: 220401664 00:09:29 INFO - PROCESS | 4258 | 00:09:31 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46562/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:09:32 INFO - PROCESS | 4258 | RSS: Main: 206401536 00:09:32 INFO - PROCESS | 4258 | 00:09:33 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:09:34 INFO - PROCESS | 4258 | RSS: Main: 211140608 00:09:34 INFO - PROCESS | 4258 | 00:09:36 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:09:37 INFO - PROCESS | 4258 | RSS: Main: 212246528 00:09:37 INFO - PROCESS | 4258 | 00:09:40 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:09:40 INFO - PROCESS | 4258 | RSS: Main: 225775616 00:09:40 INFO - PROCESS | 4258 | 00:09:43 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:09:44 INFO - PROCESS | 4258 | RSS: Main: 226357248 00:09:44 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | Cycle 1(1): loaded http://localhost:46562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46562/tests/kraken/driver.html?testName=ai-astar) 00:09:46 INFO - PROCESS | 4258 | RSS: Main: 267988992 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | __start_tp_report 00:09:46 INFO - PROCESS | 4258 | _x_x_mozilla_page_load 00:09:46 INFO - PROCESS | 4258 | _x_x_mozilla_page_load_details 00:09:46 INFO - PROCESS | 4258 | |i|pagename|runs| 00:09:46 INFO - PROCESS | 4258 | |0;ai-astar;104;115;114;115;112;112;113;114;114;112 00:09:46 INFO - PROCESS | 4258 | |1;audio-beat-detection;146;123;124;201;121;125;145;120;156;122 00:09:46 INFO - PROCESS | 4258 | |2;audio-dft;161;162;161;161;162;161;162;162;162;164 00:09:46 INFO - PROCESS | 4258 | |3;audio-fft;74;77;77;122;76;78;76;76;99;76 00:09:46 INFO - PROCESS | 4258 | |4;audio-oscillator;86;86;114;86;86;85;124;85;85;140 00:09:46 INFO - PROCESS | 4258 | |5;imaging-gaussian-blur;116;117;116;117;117;118;117;116;117;117 00:09:46 INFO - PROCESS | 4258 | |6;imaging-darkroom;146;146;147;147;147;148;148;147;146;146 00:09:46 INFO - PROCESS | 4258 | |7;imaging-desaturate;95;95;95;94;95;95;95;95;95;94 00:09:46 INFO - PROCESS | 4258 | |8;json-parse-financial;64;62;65;63;64;64;63;63;63;63 00:09:46 INFO - PROCESS | 4258 | |9;json-stringify-tinderbox;64;64;64;65;65;64;66;65;63;65 00:09:46 INFO - PROCESS | 4258 | |10;stanford-crypto-aes;81;82;81;81;91;81;84;82;81;82 00:09:46 INFO - PROCESS | 4258 | |11;stanford-crypto-ccm;138;145;133;140;133;126;135;136;170;128 00:09:46 INFO - PROCESS | 4258 | |12;stanford-crypto-pbkdf2;177;169;167;169;167;171;167;171;167;169 00:09:46 INFO - PROCESS | 4258 | |13;stanford-crypto-sha256-iterative;67;67;66;73;67;67;66;67;67;67 00:09:46 INFO - PROCESS | 4258 | __end_tp_report 00:09:46 INFO - PROCESS | 4258 | __start_cc_report 00:09:46 INFO - PROCESS | 4258 | _x_x_mozilla_cycle_collect,1901 00:09:46 INFO - PROCESS | 4258 | __end_cc_report 00:09:46 INFO - PROCESS | 4258 | __startTimestamp1467529786298__endTimestamp 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | ------- Summary: start ------- 00:09:46 INFO - PROCESS | 4258 | Number of tests: 14 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#0] ai-astar Cycles:10 Average:112.50 Median:114.00 stddev:3.21 (2.8%) stddev-sans-first:1.24 00:09:46 INFO - PROCESS | 4258 | Values: 104.0 115.0 114.0 115.0 112.0 112.0 113.0 114.0 114.0 112.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:135.00 stddev:25.53 (18.9%) stddev-sans-first:26.92 00:09:46 INFO - PROCESS | 4258 | Values: 146.0 123.0 124.0 201.0 121.0 125.0 145.0 120.0 156.0 122.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 00:09:46 INFO - PROCESS | 4258 | Values: 161.0 162.0 161.0 161.0 162.0 161.0 162.0 162.0 162.0 164.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#3] audio-fft Cycles:10 Average:83.10 Median:76.50 stddev:15.46 (20.2%) stddev-sans-first:16.04 00:09:46 INFO - PROCESS | 4258 | Values: 74.0 77.0 77.0 122.0 76.0 78.0 76.0 76.0 99.0 76.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:85.50 stddev:20.49 (24.0%) stddev-sans-first:21.29 00:09:46 INFO - PROCESS | 4258 | Values: 86.0 86.0 114.0 86.0 86.0 85.0 124.0 85.0 85.0 140.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 00:09:46 INFO - PROCESS | 4258 | Values: 116.0 117.0 116.0 117.0 117.0 118.0 117.0 116.0 117.0 117.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 00:09:46 INFO - PROCESS | 4258 | Values: 146.0 146.0 147.0 147.0 147.0 148.0 148.0 147.0 146.0 146.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 00:09:46 INFO - PROCESS | 4258 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:63.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 00:09:46 INFO - PROCESS | 4258 | Values: 64.0 62.0 65.0 63.0 64.0 64.0 63.0 63.0 63.0 63.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 00:09:46 INFO - PROCESS | 4258 | Values: 64.0 64.0 64.0 65.0 65.0 64.0 66.0 65.0 63.0 65.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:82.00 stddev:3.10 (3.8%) stddev-sans-first:3.23 00:09:46 INFO - PROCESS | 4258 | Values: 81.0 82.0 81.0 81.0 91.0 81.0 84.0 82.0 81.0 82.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:137.00 stddev:12.39 (9.0%) stddev-sans-first:13.14 00:09:46 INFO - PROCESS | 4258 | Values: 138.0 145.0 133.0 140.0 133.0 126.0 135.0 136.0 170.0 128.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.40 Median:169.00 stddev:3.10 (1.8%) stddev-sans-first:1.67 00:09:46 INFO - PROCESS | 4258 | Values: 177.0 169.0 167.0 169.0 167.0 171.0 167.0 171.0 167.0 169.0 00:09:46 INFO - PROCESS | 4258 | 00:09:46 INFO - PROCESS | 4258 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:2.01 (3.0%) stddev-sans-first:2.13 00:09:46 INFO - PROCESS | 4258 | Values: 67.0 67.0 66.0 73.0 67.0 67.0 66.0 67.0 67.0 67.0 00:09:46 INFO - PROCESS | 4258 | -------- Summary: end -------- 00:09:46 INFO - PROCESS | 4258 | 00:09:47 INFO - TEST-INFO | 4258: exit 0 00:09:47 INFO - TEST-OK | kraken | took 63682ms 00:09:47 INFO - SUITE-END | took 1183s 00:09:47 INFO - Completed test suite (00:19:44) 00:09:47 INFO - javascript benchmark 00:09:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2659.5478768609337, 2665.920022399329, 2695.851728154951, 2700.6219129321885, 2703.9459210815785, 4631.050634619904, 4708.176069187887, 4764.4759076501605, 4845.85462436127, 4861.927071093932, 4685.9062818743605, 4734.952650473494, 4738.123447162277, 4756.857294281169, 4769.236922092466, 4385.0, 4397.868063958086, 4417.3815665806815, 4436.578525040116, 4437.600615944564, 4410.933835992462, 4467.687261891669, 4495.303227999665, 4514.729116253019, 4519.073589914076, 4433.733975961452, 4464.709793863389, 4476.0, 4478.664100192483, 4501.662375321847, 4303.182395344875, 4303.462067241595, 4322.610965013164, 4332.740035597874, 4368.388425620416, 4295.914081718348, 4297.677674174418, 4341.739495630272, 4359.237133501656, 4359.585839345289, 4752.928706069411, 4762.238041913277, 4779.713217206979, 4797.520247975174, 4800.855974320776, 4957.5786058185095, 5103.413107492649, 5152.433232344439, 5156.252343410185, 5249.081410753141, 5207.114790485626, 5243.947560524422, 5253.3170687810725, 5265.921011184835, 5273.34083239595, 4668.463126740468, 4840.15297322967, 4881.4142302923165, 4881.9267710984, 4890.046440944053, 5085.160948443466, 5141.280220769096, 5147.073526765218, 5166.147585648326, 5202.765875535534, 4970.776315065831, 5011.84964451067, 5019.698818070928, 5022.573081288058, 5024.0, 5017.297578339036, 5090.211017292326, 5114.079465696211, 5140.768665410065, 5174.7930082796265, 5093.821716239914, 5154.484551544891, 5180.585553155738, 5192.1952097424955, 5195.402528709171, 5107.080725469451, 5126.256692779526, 5127.538521533005, 5129.358830146231, 5179.249008893689, 4165.187788381297, 4320.848770293024, 4330.848420305338, 4331.826726930951, 4378.190034843564, 3702.6852717518773, 3733.029412352808, 3772.490713753663, 3773.698104151716, 3820.1595648957186, 4450.933236001462, 4452.532484089189, 4466.798994045275, 4472.731636101845, 4500.842470513517, 5127.974360128175, 5149.897002060013, 5153.896922061538, 5160.509751573594, 5200.947990520046, 4248.936265956013, 4362.607365337135, 4405.31717583775, 4452.48796388413, 4468.195724769573, 4600.5629465200755, 4603.516630753723, 4609.2394754865745, 4618.168729628632, 4640.489546149921, 4657.045305712389, 4835.840126867692, 4854.708717476963, 4855.417349918032, 4902.681325713817, 5009.7244651544515, 5047.899042019212, 5061.848144555669, 5081.034603425337, 5096.541311282002, 4781.928271076006, 4808.014357056865, 4820.060088282751, 4826.0830442215865, 4858.58702010333, 4905.7792399342825, 5014.047331007243, 5023.1711767557945, 5023.2716256143385, 5067.366579177603, 5131.640785145004, 5140.871478212925, 5151.871203219949, 5175.171972484384, 5223.503767142115, 4992.925106123484, 5003.424606170337, 5024.860779634166, 5026.421961474477, 5066.771995260148, 4848.951510484992, 4909.042736666316, 4943.876403089807, 5009.77456014473, 5023.924641130459, 4906.411230652345, 4916.164252077227, 4932.038252540864, 4949.678270912379, 4962.950370496249, 5006.3992320921725, 5017.473165317735, 5017.54842064216, 5018.42288136869, 5073.188289873602, 4797.928031079467, 4821.4696383397095, 4843.418789745253, 4892.633052521004, 4901.681390709592], "value": 4698.486647859781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.4222493057082, 569.5728203847115, 576.5070864410928, 581.2879222951882, 588.2441063233746, 28290.151295461143, 40525.39211911824, 41706.37440438392, 41721.374179387334, 44977.55022449775, 41702.58297417025, 41816.16367672645, 41978.58021419785, 41998.99999999993, 42056.0, 7540.924590754077, 7821.491603045798, 7962.044554653449, 7967.840643187133, 7968.721094761684, 7489.0, 7717.18969508202, 7718.768436946901, 10060.496975151213, 10343.172546196323, 10401.583936642524, 10434.373937563732, 10435.321704089249, 10452.320599161068, 10456.0, 10297.330673506236, 10307.072363487323, 10354.119899808526, 10370.533326000348, 10376.0, 7585.469017168801, 7809.062912450486, 7814.023247094114, 10043.598256069748, 10447.634332798316, 39896.40155397671, 40229.0, 40260.0, 40313.19373612541, 40322.59677403218, 3077.5999120114466, 3085.1207405889213, 3225.822579758112, 3291.358185153872, 3766.114962983697, 30381.936632217767, 30770.615232324428, 31146.688533114833, 31655.525167122512, 32217.677823221704, 3590.9102272443397, 3614.1145419372424, 3653.204264061416, 3665.4318580620047, 3694.538182727159, 258886.0, 260780.0, 261431.0, 262744.0, 264024.0, 46046.5395346049, 53355.0, 53686.19470707943, 53760.73119634377, 53895.19157212645, 53715.46284537105, 55233.447665523636, 55443.0, 61398.69300653468, 61490.38509614936, 13793.379297931617, 13861.4455421782, 13888.58334249954, 13907.165570065828, 13911.582652520441, 10102.19182465401, 11288.0969522438, 11391.544338226544, 11409.315441073562, 11416.31502109876, 15022.624434389229, 15445.691086178214, 15447.227638618024, 15817.339179386228, 15943.44197953066, 5410.269613602192, 5462.3445186577865, 5551.25058117149, 5681.147827825859, 5732.197492351074, 860.2816648098783, 904.968326108583, 970.2626004236831, 974.1086905481546, 1034.462079718557, 27635.0, 28921.56617650737, 28967.0, 28972.27569310784, 29394.0, 1004.6935684616116, 1014.5282443663732, 1023.048564834696, 1027.8355463125863, 1032.5972870580479, 15402.152881591395, 15419.229038548028, 15424.151671658166, 15468.381264749509, 15599.532014039596, 114786.42606786912, 115254.0, 115332.42333788445, 115989.42005290088, 116067.41966290283, 49701.50298497041, 50617.73455663638, 50746.23880641793, 51038.744806276474, 51313.0, 1083.772407794372, 1140.503880811833, 1150.8158694608821, 1203.3802591665299, 1210.346412937004, 28398.29004274827, 29891.252718682208, 30314.0, 30898.53652195264, 31184.376312474076, 44116.77941610271, 44836.32745508885, 44973.32540011968, 44985.0, 45017.774911125234, 362302.0, 372873.0, 373887.0, 374382.0, 374432.0, 33954.66045339515, 34800.82599586986, 37577.248455031295, 42850.0, 44041.0, 42463.787681061396, 42942.35586466268, 43065.78467107644, 43597.47408840675, 43724.0, 37344.253114938096, 37681.81159094187, 37729.2454150921, 38281.0, 38399.0, 37932.24135517219, 38029.80985095168, 38076.61923380731, 38190.618093819816, 38555.42166867555, 37126.6287337134, 37219.441708374936, 37428.81285593555, 37833.243335132596, 37964.0, 32782.67217327796, 33032.0, 33376.83311583426, 33997.830010849786, 34041.0, 25504.489910202064, 26002.349941250865, 26368.0, 26388.208353749418, 26667.0], "value": 17839.710088621607, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.961064282929, 373.17528262539787, 378.990525236869, 384.6614978818639, 400.4373854734098, 13269.535566255183, 14268.001239913197, 17244.91377543112, 17322.393716219936, 17340.0, 15360.769588456182, 17150.399736009243, 17378.21798019089, 17421.390251341207, 17447.389341373055, 5139.845804625857, 5335.493128152839, 5369.355677318727, 5372.838814835562, 5375.516203541681, 3853.7880416577077, 5284.075286824809, 5827.096799995987, 6136.202293701813, 6157.661328626924, 6181.721822517998, 6193.504519638418, 6213.285472170691, 6216.316205217422, 6217.440430361266, 5266.973665131669, 5552.750126244308, 5553.416891226403, 5652.773889044433, 5654.660720356792, 3438.2435864109857, 5021.6735912165595, 5248.081585722522, 5254.395744489356, 5447.509724124848, 21151.682724759143, 21676.02457889383, 21892.12431502738, 22154.22460213885, 22154.778452215593, 1950.1419375474745, 1995.8802471851736, 1997.490639886834, 2027.787082356346, 2032.8576999610034, 9548.474833884133, 9901.603935842557, 10024.19806415485, 10157.54291056897, 13168.485624153249, 1752.6582316448298, 1770.849477794389, 1790.239148361941, 1803.4409333106778, 1853.7960824309316, 69357.65321173413, 76288.23711762922, 76509.61745191239, 77086.6145669268, 77716.0, 9652.179564736936, 9863.704088877344, 9977.650782222732, 10109.494525273707, 10111.7472063199, 13452.058355915187, 13471.865281347062, 13514.797278040838, 13711.657208569866, 13717.245551494563, 7406.814829629302, 7418.295261950106, 7579.848403031909, 7653.655585498666, 7661.578613176329, 7411.993190112678, 7446.85106297871, 7508.324250817453, 7632.389408847279, 7632.885506717404, 4154.750714957112, 4216.8313267469575, 4239.894002649897, 4360.106178233455, 4614.377059097046, 1694.5085925081835, 1699.5836020175136, 1721.0878234535717, 1760.287083731091, 1760.7887053553657, 842.1452225990624, 842.616609442702, 849.180540778152, 868.9217970382696, 942.7784470649392, 17668.734968975477, 17713.822861771216, 17928.372506962456, 18349.7247541287, 18431.907840460713, 822.2392348303376, 837.3719710217337, 885.8759773631697, 896.5606852642159, 918.8989211186764, 9707.077827606367, 9848.113669769611, 10175.045959555544, 10190.133838623651, 10192.0, 437971.0, 448055.0, 449801.0, 451124.0, 451528.0, 50659.24011139836, 67409.66295168492, 72553.6372318135, 82427.0, 84272.0, 832.1221111727307, 882.6513527156737, 891.157855826238, 896.7847716548112, 902.9683961061463, 15344.769828452805, 15887.443939462064, 16040.358385664442, 16042.839571604134, 16529.834701652828, 40821.958902055376, 40976.79511602542, 41057.0, 41607.58392416158, 41706.0, 53629.7318513405, 56121.07152499286, 56240.156397654886, 56870.0, 57997.420025799205, 7844.294013538809, 8027.2775450209765, 8028.518288902685, 8204.179582041748, 8246.257836794717, 11945.604923640634, 12494.187877787916, 12555.74888502243, 12752.107352485236, 13014.4143513544, 10861.34831910088, 10957.287776294515, 10976.23166378346, 11143.33140011602, 11461.255018423775, 10958.397288149228, 11006.614768483065, 11022.614208502664, 11090.112790976542, 11264.94367528157, 11034.724131896766, 11228.606998755004, 11366.374849383363, 11454.312741235553, 11610.477528511406, 7775.377969762519, 8030.959845200737, 8045.0, 8293.668253270043, 8376.0, 8292.04641466239, 10419.635312764018, 11030.50362733663, 11060.281081729661, 11282.548698051985], "value": 8510.87641981918, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1285.8585555588877, 1370.451819272291, 1432.0619993903993, 1509.675419784746, 1718.6390857919837, 6979.511434199601, 7632.3894088472925, 7872.842543149133, 7878.842423151534, 8089.838203235931, 7791.766247012599, 7956.840863182747, 7960.08459027212, 7964.721234756785, 7968.920310796891, 2229.665550167472, 2246.70792796937, 2253.1663284584642, 2255.537614788964, 2260.864348139117, 2208.436848603612, 2237.9552408951813, 2266.875321857297, 2268.6710426988075, 2269.2851751698245, 2234.084025549525, 2258.5144193998285, 2266.6826644269813, 2273.9886300568473, 2277.977220227793, 2176.053416763697, 2206.702095217141, 2215.3796936857707, 2216.612092883739, 2218.301235110943, 2109.947251318714, 2115.471132216946, 2118.9152433902627, 2119.078200982562, 2120.74551053873, 7932.682692692285, 7988.121306656263, 8011.319037881787, 8067.2739453448885, 8292.502449853027, 4668.36977008103, 4769.523047695237, 4777.593904518086, 4781.450133234688, 4781.928271075936, 2893.23329317731, 2983.6270466191727, 3014.6533148687963, 3021.289996850738, 3026.031669865644, 952.0336858089045, 962.5957098018779, 970.8592254123113, 1002.5388321372178, 1004.0210794475402, 11251.54993800247, 11343.78796134754, 11346.489407976578, 11363.488643011084, 11388.088952883667, 4214.4099826024385, 4220.240356735817, 4376.824927002948, 4386.934195987063, 4403.031333106712, 8812.206901378906, 8868.0, 8875.112488751203, 8879.023307436177, 8886.733397997941, 4723.5984941279685, 4745.738984355894, 4751.049790042005, 4751.287306903992, 4752.5485078917445, 3755.868544600926, 3759.041444431707, 3770.9245815083545, 3886.8833934981994, 4366.366876802846, 3533.8763143290375, 3590.551181102362, 3674.063113905937, 3675.081229692577, 3702.426123950792, 1093.8359246112987, 1109.5949978257877, 1121.6130434999911, 1124.145649306533, 1131.1685910855515, 723.9022731931227, 786.0253285925411, 846.2848892685671, 847.0216899481109, 852.2117041736369, 5123.359580052494, 5392.811251606175, 5464.890702186013, 5504.256925315031, 5508.807191748269, 833.7540425574471, 839.47532792005, 846.3609974469236, 867.1242045534055, 875.8598624220094, 3529.9294014120087, 3723.162288485069, 3779.055236190952, 3797.2974999625158, 3813.275477659236, 9825.705228843146, 10183.439910804977, 10287.794244115075, 10325.793484130276, 10331.225158113171, 10205.744856378502, 10214.285000049926, 10226.0, 10232.02795734404, 10238.948805255926, 7219.675114619855, 7559.962200188964, 7609.0, 7667.3099421052375, 7701.3068823806125, 5645.4072322405555, 5702.173184888251, 5721.342045664635, 5756.769729210785, 5893.233879595625, 8858.069902660385, 9177.724668259963, 9197.77005574866, 9425.340226184102, 9480.194183494481, 9192.356535042483, 9486.905130948879, 9504.572294246915, 9512.714618561455, 9656.37233579815, 6926.722931082902, 7016.36852683261, 7038.085048943604, 7044.436445084279, 7050.294970502909, 7476.700931962878, 7588.696452142073, 7653.502522336031, 7656.617169141409, 7664.73173438927, 6896.655167241517, 6903.1371078615175, 6983.3714965653335, 6994.825129371807, 7054.894176587252, 6842.24735279109, 6932.376086152271, 6949.061876646588, 7021.789346319619, 7046.330598593126, 6126.754929802758, 6145.84635384119, 6158.568900176944, 6164.907526387197, 6187.195664563578, 4480.9103817924115, 4558.088382323483, 4625.398698169217, 4654.045920586339, 4718.622510199244, 4642.558956899089, 4729.10147072069, 4734.100520901018, 4750.168720473972, 4847.345608342828], "value": 4223.741206600063, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.26746795671033, 296.46192161227356, 298.0, 304.4428695487258, 308.99382012359746, 4807.375041244638, 4833.250846118857, 4833.734144622049, 4845.7819398127085, 4848.418189817223, 4778.7062210139065, 4790.473047964721, 4791.3052607372, 4809.254565542338, 4813.951860481394, 3626.727995400349, 3708.128589781399, 3734.495843061179, 3762.1159027628487, 3763.0, 3723.515942927416, 3796.335641262782, 3804.4293355996683, 3808.7143464240153, 3816.1985982943615, 3718.498002769632, 3731.477593136963, 3742.8877133685896, 3759.492468516756, 3775.1505911169907, 3553.3603951288756, 3654.5979942206336, 3760.2291530236507, 3766.7928263945473, 3803.7337386382965, 3318.5188147718445, 3320.701136897667, 3329.0, 3331.68349006844, 3347.5313456116164, 4642.442906851199, 4645.535446455361, 4645.651576131803, 4654.348391225231, 4733.124371991193, 4200.831966721327, 4273.764942928137, 4325.502567204749, 4326.09152078064, 4329.913401731979, 4948.901021979576, 4960.925586116211, 4963.627727920384, 4982.750862456899, 5001.3998320201445, 3655.4151335786146, 3662.08447888028, 3668.1196512836727, 3669.174435751961, 3669.853205871762, 5134.743262836843, 5218.895622087575, 5245.239440281138, 5249.212618107276, 5250.501202385767, 3713.20166164276, 3737.252549490113, 3738.738288319846, 3746.456763769238, 3753.0992561784974, 3800.3349413852466, 3805.1438426354403, 3806.238752249561, 3809.5047643806683, 3810.828512716879, 4302.569743025735, 4305.418768466279, 4305.612494875476, 4306.913861722811, 4314.158739045856, 4935.654504184673, 4990.8253211137435, 5016.54851063406, 5019.0965626187635, 5087.16061849798, 1294.3140135728079, 1301.1412467771227, 1303.589369348652, 1310.1353106949368, 1315.697389600378, 811.0834756724981, 853.5007020892847, 853.5945425922637, 855.6064210463256, 862.6980556805194, 4352.7823608819435, 4465.4864690561, 4465.620422264144, 4467.709598876063, 4467.754273514923, 5084.7966081356335, 5097.7706003229205, 5099.64302498829, 5138.0, 5141.203113517411, 4413.426254586883, 4429.0, 4433.844815431445, 4434.977825110918, 4476.328550717353, 2562.93592660185, 2755.655543057118, 2757.310672331917, 2761.8480983546096, 2765.612814206013, 4876.951455437098, 4895.412550493964, 4900.142475066849, 4907.1657818171, 4925.1873440881855, 5021.196608542616, 5249.482846492859, 5264.157734762496, 5270.815521456807, 5287.20691896211, 4841.733704646137, 4878.853634391045, 4895.143349913751, 4904.779284932187, 4911.729854857946, 5125.0, 5201.089809283436, 5210.218467229794, 5222.425533191422, 5238.816641417532, 4882.926756098733, 5058.595312375075, 5125.974370128126, 5174.6119041071315, 5175.92236116451, 4852.8544143675745, 4873.829415970424, 4873.853784386474, 4884.8046078157895, 4888.144574699342, 3767.321882061255, 3790.4882840816135, 3812.714046446472, 3818.3890577507464, 3838.136419305634, 4987.825426110068, 5189.0, 5193.792248310026, 5198.0, 5227.085260079547, 4998.850034498971, 5015.473375295542, 5019.799208031784, 5037.143685573388, 5053.31780209667, 5031.767217031851, 5051.520105589963, 5077.466865979019, 5122.282880396673, 5132.5893928486375, 4894.95105048945, 4983.775730092082, 4993.101241776515, 5013.974930125326, 5044.319016932667, 4846.17615005457, 4951.356323677899, 5069.7465126744255, 5081.060003899291, 5089.465606111305, 4944.579710724629, 4953.356063711839, 4960.404751429852, 5006.6495345325475, 5029.974850125726], "value": 3808.3715642815578, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [660.1978596005852, 678.1658559971238, 681.3220845258962, 737.5058710663855, 757.8938948547203, 13744.725105497884, 14806.111633302009, 14810.555683329518, 14829.777553336682, 14858.628534286649, 13358.732825343488, 13584.932075339635, 13828.792568111487, 13830.308484575782, 13836.239006854645, 628.7327885648604, 768.2471178245323, 774.8140446292891, 779.7426849139798, 792.1603100713234, 781.86708259596, 786.9803254918615, 792.0772300270178, 792.5086446403238, 792.5759718550577, 836.506461187899, 837.2966707965307, 841.6927821345196, 843.2284459719349, 843.28320927212, 652.5203975078314, 801.2548330053048, 823.3289868756974, 826.6775957376628, 839.2656425627575, 597.0, 601.4947444146937, 602.2441835496468, 603.3574243430733, 603.643850128422, 13504.864951350459, 13528.526501572496, 13530.93234533831, 13547.119437236646, 13548.187108773505, 12546.81179782304, 12819.615411537668, 12831.230126192366, 13087.41106650203, 13172.077954543185, 11882.821757673642, 11951.0, 11978.940105299418, 12034.879651203464, 12296.569620063256, 4140.544540100586, 4153.501579810412, 4161.542230354658, 4161.875143745662, 4165.12532368203, 63460.36539634591, 63660.68169659168, 63927.68036159836, 64673.3532664672, 65460.67269663669, 10277.434741089312, 10403.94798026005, 10404.803447603472, 10425.11386532138, 10469.68590942273, 18455.0, 18517.07414629263, 18688.0, 18698.34555790541, 18713.81286187148, 7055.435565154775, 7106.538075025107, 7141.642917854087, 7145.857082858418, 7317.487775855746, 3835.808209589565, 3913.8043097844993, 3949.664278536299, 3986.362182050858, 3992.840286388512, 2863.9427211455654, 2953.6307961504813, 2954.0251716933362, 2954.896578619738, 2959.215807810932, 503.94204666463827, 503.9571636410946, 517.244822559068, 518.2899427783885, 518.9455107213688, 481.5184815184815, 499.6202885806813, 542.4548328929401, 599.0475144520234, 624.2321944008804, 8164.428490005643, 8195.71315003972, 8319.916800831914, 8325.0, 8434.493930364199, 531.1315998342737, 546.778554685353, 547.6905548365161, 564.4214679952998, 606.6663335165641, 3820.6561409472724, 3891.7664940103687, 3910.7262491625884, 3926.842926282917, 4023.879283621496, 77118.22881771222, 79655.0, 79913.20086799173, 92808.0, 105868.47065764776, 74838.0, 75900.62049689826, 76944.0, 83119.0, 83941.58029209815, 57825.710871446216, 71543.6422817893, 73914.0, 74618.96477375805, 75228.62385688037, 7934.523928564305, 8112.59437028158, 8207.425480216327, 8227.177282271725, 8365.372597055124, 30349.544756829106, 33170.66829331676, 39274.60725392824, 40419.3937090938, 43327.35008974931, 56824.43175568357, 57226.42773572211, 58692.70653646704, 59629.0, 60245.397546025735, 11544.769104618028, 11687.59369848129, 11836.349000805038, 11980.880191198325, 12289.385530723606, 13460.25968571738, 13673.085767992688, 13745.587632371045, 14394.496192633627, 14424.711505769616, 12471.189372690746, 12509.687257818263, 12871.485140594647, 13161.341932903126, 13492.392842321922, 11610.303381797119, 12717.618471445872, 12956.28740419286, 13069.934650326688, 13166.47334106625, 11697.824532631847, 11732.237404568676, 11735.23720958135, 12226.510939562319, 12566.994640428926, 9831.213502919893, 10111.03945125212, 10247.38515689061, 10260.435676037889, 10417.374957502574, 9718.659846905324, 10203.540840662337, 10591.417472038804, 10709.303895246783, 10731.517081731497], "value": 6666.159385143464, "unit": "score"}], "name": "dromaeo_css", "value": 6515.48937637364, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 115.0, 112.0, 112.0, 113.0, 114.0, 114.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 124.0, 201.0, 121.0, 125.0, 145.0, 120.0, 156.0, 122.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 162.0, 164.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 77.0, 122.0, 76.0, 78.0, 76.0, 76.0, 99.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 114.0, 86.0, 86.0, 85.0, 124.0, 85.0, 85.0, 140.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 146.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 65.0, 63.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 66.0, 65.0, 63.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 81.0, 91.0, 81.0, 84.0, 82.0, 81.0, 82.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 145.0, 133.0, 140.0, 133.0, 126.0, 135.0, 136.0, 170.0, 128.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 169.0, 167.0, 169.0, 167.0, 171.0, 167.0, 171.0, 167.0, 169.0], "value": 169.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 73.0, 67.0, 67.0, 66.0, 67.0, 67.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1537.5, "alertThreshold": 2.0}]} 00:09:47 INFO - Return code: 0 00:09:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:09:47 INFO - # TBPL SUCCESS # 00:09:47 INFO - Running post-action listener: _resource_record_post_action 00:09:47 INFO - ##### 00:09:47 INFO - ##### Finished run-tests step (success) 00:09:47 INFO - ##### 00:09:47 INFO - Running post-run listener: _resource_record_post_run 00:09:47 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 544268288; Read time: 16; Write time: 672472 00:09:47 INFO - TinderboxPrint: CPU usage
13.0% 00:09:47 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 00:09:47 INFO - TinderboxPrint: I/O write bytes / time
544,268,288 / 672,472 00:09:47 INFO - TinderboxPrint: CPU idle
8,339.5 (87.0%) 00:09:47 INFO - TinderboxPrint: CPU user
1,200.5 (12.5%) 00:09:47 INFO - TinderboxPrint: Swap in / out
0 / 0 00:09:47 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 256577536; Read time: 0; Write time: 357048 00:09:47 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287690752; Read time: 16; Write time: 315424 00:09:48 INFO - Running post-run listener: _upload_blobber_files 00:09:48 INFO - Blob upload gear active. 00:09:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:09:48 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 00:09:48 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'] 00:09:48 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 00:09:48 INFO - (blobuploader) - INFO - Open directory for files ... 00:09:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:09:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:09:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:52 INFO - (blobuploader) - INFO - Done attempting. 00:09:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:09:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:09:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:53 INFO - (blobuploader) - INFO - Done attempting. 00:09:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:09:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:09:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:54 INFO - (blobuploader) - INFO - Done attempting. 00:09:54 INFO - (blobuploader) - INFO - Iteration through files over. 00:09:54 INFO - Return code: 0 00:09:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:09:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:09:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/236173ea2a83be3718e9b862626fb9f22f71f00ee472967ab96906c0dc9adc4805121ac02efd1aed646236f1a8c349f7b98ea38937433949fba535c9766bd716", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4d252f7834106473e9f9523a0926026925aec661e74d90fcd6cc4993a677d1e3175f7a226ca29a2f3ab01bfc624b91490102990a8babd4cabefb01021314516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bdf1465f5223c10732c7b250e78e296fd4833487193c0f536212c0c18b140af9bd20c758d1a3213eae75fc5ead043e5cc76dc0bebfc31028f29220f8ebea0b5b"} 00:09:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:09:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:09:54 INFO - Contents: 00:09:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/236173ea2a83be3718e9b862626fb9f22f71f00ee472967ab96906c0dc9adc4805121ac02efd1aed646236f1a8c349f7b98ea38937433949fba535c9766bd716", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4d252f7834106473e9f9523a0926026925aec661e74d90fcd6cc4993a677d1e3175f7a226ca29a2f3ab01bfc624b91490102990a8babd4cabefb01021314516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bdf1465f5223c10732c7b250e78e296fd4833487193c0f536212c0c18b140af9bd20c758d1a3213eae75fc5ead043e5cc76dc0bebfc31028f29220f8ebea0b5b"} 00:09:54 INFO - Running post-run listener: copy_logs_to_upload_dir 00:09:54 INFO - Copying logs to upload dir... 00:09:54 INFO - mkdir: /builds/slave/test/build/upload/logs 00:09:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.045476 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-03 00:09:52.820218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 00:09:52.824962) ========= 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=fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/236173ea2a83be3718e9b862626fb9f22f71f00ee472967ab96906c0dc9adc4805121ac02efd1aed646236f1a8c349f7b98ea38937433949fba535c9766bd716", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4d252f7834106473e9f9523a0926026925aec661e74d90fcd6cc4993a677d1e3175f7a226ca29a2f3ab01bfc624b91490102990a8babd4cabefb01021314516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bdf1465f5223c10732c7b250e78e296fd4833487193c0f536212c0c18b140af9bd20c758d1a3213eae75fc5ead043e5cc76dc0bebfc31028f29220f8ebea0b5b"} build_url:https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/cQ9M3YcnS4aI-r_a4ztlaw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/236173ea2a83be3718e9b862626fb9f22f71f00ee472967ab96906c0dc9adc4805121ac02efd1aed646236f1a8c349f7b98ea38937433949fba535c9766bd716", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/a4d252f7834106473e9f9523a0926026925aec661e74d90fcd6cc4993a677d1e3175f7a226ca29a2f3ab01bfc624b91490102990a8babd4cabefb01021314516", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bdf1465f5223c10732c7b250e78e296fd4833487193c0f536212c0c18b140af9bd20c758d1a3213eae75fc5ead043e5cc76dc0bebfc31028f29220f8ebea0b5b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-03 00:09:52.853201) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 00:09:52.853622) ========= 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=fa44defc139c4ba8574fe040000001db-1467528552.478433-1911322852 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-03 00:09:52.903342) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 00:09:52.906693) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-03 00:09:52.906997) ========= ========= Total master_lag: 0.46 =========