builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1467313593.09 results: success (0) buildid: 20160630105729 builduid: 0b2cd5c6e7f34a15a992b37d64558a06 revision: 8d2952ce50f321c39cc2bd8a2c65deb322e5e132 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.085910) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.093820) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.094268) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.141545) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.141869) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-30 12:06:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-06-30 12:06:33 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159307 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.316154) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.316498) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019018 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:33.367069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 12:06:33.367417) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8d2952ce50f321c39cc2bd8a2c65deb322e5e132 --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 8d2952ce50f321c39cc2bd8a2c65deb322e5e132 --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=9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-30 12:06:33,637 truncating revision to first 12 chars 2016-06-30 12:06:33,637 Setting DEBUG logging. 2016-06-30 12:06:33,637 attempt 1/10 2016-06-30 12:06:33,637 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8d2952ce50f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 12:06:34,984 unpacking tar archive at: autoland-8d2952ce50f3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.692243 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 12:06:35.079430) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:35.080284) ========= script_repo_revision: 8d2952ce50f321c39cc2bd8a2c65deb322e5e132 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:35.081251) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:35.081970) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 12:06:35.093726) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-06-30 12:06:35.094064) ========= /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=9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:06:35 INFO - MultiFileLogger online at 20160630 12:06:35 in /builds/slave/test 12:06:35 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 12:06:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:06:35 INFO - {'append_to_log': False, 12:06:35 INFO - 'base_work_dir': '/builds/slave/test', 12:06:35 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 12:06:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:06:35 INFO - 'branch': 'Autoland-Non-PGO', 12:06:35 INFO - 'buildbot_json_path': 'buildprops.json', 12:06:35 INFO - 'config_files': ('talos/linux_config.py',), 12:06:35 INFO - 'default_actions': ('clobber', 12:06:35 INFO - 'read-buildbot-config', 12:06:35 INFO - 'download-and-extract', 12:06:35 INFO - 'populate-webroot', 12:06:35 INFO - 'create-virtualenv', 12:06:35 INFO - 'install', 12:06:35 INFO - 'run-tests'), 12:06:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:06:35 INFO - 'download_minidump_stackwalk': True, 12:06:35 INFO - 'download_symbols': 'ondemand', 12:06:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:06:35 INFO - 'tooltool.py': '/tools/tooltool.py', 12:06:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:06:35 INFO - '/tools/misc-python/virtualenv.py')}, 12:06:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:06:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:06:35 INFO - 'installer_path': 'installer.exe', 12:06:35 INFO - 'log_level': 'info', 12:06:35 INFO - 'log_name': 'talos', 12:06:35 INFO - 'log_to_console': True, 12:06:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:06:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:06:35 INFO - 'opt_config_files': (), 12:06:35 INFO - 'pip_index': False, 12:06:35 INFO - 'sps_profile': False, 12:06:35 INFO - 'sps_profile_interval': 0, 12:06:35 INFO - 'suite': 'dromaeojs', 12:06:35 INFO - 'system_bits': '32', 12:06:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:06:35 INFO - 'title': 'talos-linux64-ix-022', 12:06:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:06:35 INFO - 'use_talos_json': True, 12:06:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:06:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:06:35 INFO - 'work_dir': 'build'} 12:06:35 INFO - ##### 12:06:35 INFO - ##### Running clobber step. 12:06:35 INFO - ##### 12:06:35 INFO - Running pre-action listener: _resource_record_pre_action 12:06:35 INFO - Running main action method: clobber 12:06:35 INFO - rmtree: /builds/slave/test/build 12:06:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:06:36 INFO - Running post-action listener: _resource_record_post_action 12:06:36 INFO - ##### 12:06:36 INFO - ##### Finished clobber step (success) 12:06:36 INFO - ##### 12:06:36 INFO - ##### 12:06:36 INFO - ##### Running read-buildbot-config step. 12:06:36 INFO - ##### 12:06:36 INFO - Running pre-action listener: _resource_record_pre_action 12:06:36 INFO - Running main action method: read_buildbot_config 12:06:36 INFO - Using buildbot properties: 12:06:36 INFO - { 12:06:36 INFO - "project": "", 12:06:36 INFO - "product": "firefox", 12:06:36 INFO - "script_repo_revision": "production", 12:06:36 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 12:06:36 INFO - "repository": "", 12:06:36 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 12:06:36 INFO - "buildid": "20160630105729", 12:06:36 INFO - "pgo_build": "False", 12:06:36 INFO - "basedir": "/builds/slave/test", 12:06:36 INFO - "buildnumber": 22, 12:06:36 INFO - "slavename": "talos-linux64-ix-022", 12:06:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:06:36 INFO - "platform": "ubuntu64_hw", 12:06:36 INFO - "scheduler": "tests-autoland-linux64-talos", 12:06:36 INFO - "branch": "autoland", 12:06:36 INFO - "repo_path": "integration/autoland", 12:06:36 INFO - "revision": "8d2952ce50f321c39cc2bd8a2c65deb322e5e132", 12:06:36 INFO - "stage_platform": "linux64", 12:06:36 INFO - "builduid": "0b2cd5c6e7f34a15a992b37d64558a06", 12:06:36 INFO - "slavebuilddir": "test" 12:06:36 INFO - } 12:06:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 12:06:36 INFO - Running post-action listener: _resource_record_post_action 12:06:36 INFO - ##### 12:06:36 INFO - ##### Finished read-buildbot-config step (success) 12:06:36 INFO - ##### 12:06:36 INFO - ##### 12:06:36 INFO - ##### Running download-and-extract step. 12:06:36 INFO - ##### 12:06:36 INFO - Running pre-action listener: _resource_record_pre_action 12:06:36 INFO - Running main action method: download_and_extract 12:06:36 INFO - mkdir: /builds/slave/test/build/tests 12:06:36 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')]} 12:06:36 INFO - https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:06:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:06:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:06:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:39 INFO - Downloaded 1361 bytes. 12:06:39 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 12:06:39 INFO - Using the following test package requirements: 12:06:39 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:06:39 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:06:39 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:06:39 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'jsshell-linux-x86_64.zip'], 12:06:39 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:06:39 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 12:06:39 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:06:39 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:06:39 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:06:39 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 12:06:39 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:06:39 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:06:39 INFO - https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:06:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:06:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:06:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:40 INFO - Downloaded 22701821 bytes. 12:06:40 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'] 12:06:40 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 12:06:41 INFO - Return code: 0 12:06:41 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 12:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:06:41 INFO - https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:06:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:06:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 12:06:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:42 INFO - Downloaded 22701821 bytes. 12:06:42 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'] 12:06:42 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 12:06:43 INFO - Return code: 0 12:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:06:43 INFO - https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:06:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:06:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 12:06:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:45 INFO - Downloaded 14405242 bytes. 12:06:45 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'] 12:06:45 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 12:06:45 INFO - Return code: 0 12:06: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')]} 12:06:45 INFO - https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:06:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:06:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:06:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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 12:06:46 INFO - Downloaded 56456883 bytes. 12:06:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:06:46 INFO - mkdir: /builds/slave/test/properties 12:06:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:06:46 INFO - Writing to file /builds/slave/test/properties/build_url 12:06:46 INFO - Contents: 12:06:46 INFO - build_url:https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 12:06:47 INFO - Running post-action listener: _resource_record_post_action 12:06:47 INFO - Running post-action listener: set_extra_try_arguments 12:06:47 INFO - ##### 12:06:47 INFO - ##### Finished download-and-extract step (success) 12:06:47 INFO - ##### 12:06:47 INFO - ##### 12:06:47 INFO - ##### Running populate-webroot step. 12:06:47 INFO - ##### 12:06:47 INFO - Running pre-action listener: _resource_record_pre_action 12:06:47 INFO - Running main action method: populate_webroot 12:06:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:06:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:06:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:06:47 INFO - '--tppagecycles', 12:06:47 INFO - '7'], 12:06:47 INFO - 'tests': ['tsvgm']}}, 12:06:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:06:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:06:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:06:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:06:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:06:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:06:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['damp', 'tps']}, 12:06:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'tests': ['damp', 'tps']}, 12:06:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['dromaeo_dom']}, 12:06:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:06:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['basic_compositor_video']}, 12:06:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:06:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint']}, 12:06:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint']}, 12:06:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint']}, 12:06:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint']}, 12:06:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint']}, 12:06:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['a11yr', 12:06:47 INFO - 'ts_paint', 12:06:47 INFO - 'tpaint', 12:06:47 INFO - 'sessionrestore', 12:06:47 INFO - 'sessionrestore_no_auto_restore', 12:06:47 INFO - 'tabpaint']}, 12:06:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['tsvgx', 12:06:47 INFO - 'tsvgr_opacity', 12:06:47 INFO - 'tart', 12:06:47 INFO - 'tscrollx', 12:06:47 INFO - 'cart']}, 12:06:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:06:47 INFO - 'tsvgr_opacity', 12:06:47 INFO - 'tart', 12:06:47 INFO - 'tscrollx', 12:06:47 INFO - 'cart']}, 12:06:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-e10s'], 12:06:47 INFO - 'tests': ['tp5o']}, 12:06:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'tests': ['tp5o']}, 12:06:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'talos_options': ['--disable-e10s', 12:06:47 INFO - '--xperf_path', 12:06:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:06:47 INFO - 'tests': ['tp5n']}, 12:06:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:06:47 INFO - 'talos_options': ['--xperf_path', 12:06:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:06:47 INFO - 'tests': ['tp5n']}}, 12:06:47 INFO - 'talos.zip': {'path': '', 12:06:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:06:47 INFO - Running post-action listener: _resource_record_post_action 12:06:47 INFO - ##### 12:06:47 INFO - ##### Finished populate-webroot step (success) 12:06:47 INFO - ##### 12:06:47 INFO - ##### 12:06:47 INFO - ##### Running create-virtualenv step. 12:06:47 INFO - ##### 12:06:47 INFO - Running pre-action listener: _resource_record_pre_action 12:06:47 INFO - Running main action method: create_virtualenv 12:06:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:06:47 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 12:06:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:06:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:06:47 INFO - Using real prefix '/usr' 12:06:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:06:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:06:50 INFO - Installing pip.................done. 12:06:50 INFO - Return code: 0 12:06:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:06:50 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')]} 12:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:50 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')]} 12:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:50 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:06:50 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 12:06:50 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 12:06:50 INFO - Using env: {'DISPLAY': ':0', 12:06:50 INFO - 'HOME': '/home/cltbld', 12:06:50 INFO - 'LANG': 'en_US.UTF-8', 12:06:50 INFO - 'LANGUAGE': 'en_US:en', 12:06:50 INFO - 'LOGNAME': 'cltbld', 12:06:50 INFO - 'MAIL': '/var/mail/cltbld', 12:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:50 INFO - 'MOZ_NO_REMOTE': '1', 12:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:50 INFO - 'NO_EM_RESTART': '1', 12:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:50 INFO - 'PWD': '/builds/slave/test', 12:06:50 INFO - 'SHELL': '/bin/bash', 12:06:50 INFO - 'SHLVL': '1', 12:06:50 INFO - 'TERM': 'linux', 12:06:50 INFO - 'TMOUT': '86400', 12:06:50 INFO - 'USER': 'cltbld', 12:06:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:50 INFO - '_': '/tools/buildbot/bin/python'} 12:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:50 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 12:06:50 INFO - Cleaning up... 12:06:50 INFO - Return code: 0 12:06:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:06:50 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')]} 12:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:50 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')]} 12:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:50 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:06:50 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 12:06:50 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 12:06:50 INFO - Using env: {'DISPLAY': ':0', 12:06:50 INFO - 'HOME': '/home/cltbld', 12:06:50 INFO - 'LANG': 'en_US.UTF-8', 12:06:50 INFO - 'LANGUAGE': 'en_US:en', 12:06:50 INFO - 'LOGNAME': 'cltbld', 12:06:50 INFO - 'MAIL': '/var/mail/cltbld', 12:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:50 INFO - 'MOZ_NO_REMOTE': '1', 12:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:50 INFO - 'NO_EM_RESTART': '1', 12:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:50 INFO - 'PWD': '/builds/slave/test', 12:06:50 INFO - 'SHELL': '/bin/bash', 12:06:50 INFO - 'SHLVL': '1', 12:06:50 INFO - 'TERM': 'linux', 12:06:50 INFO - 'TMOUT': '86400', 12:06:50 INFO - 'USER': 'cltbld', 12:06:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:50 INFO - '_': '/tools/buildbot/bin/python'} 12:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:50 INFO - Downloading/unpacking psutil>=3.1.1 12:06:50 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 12:06:50 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 12:06:50 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 12:06:50 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 12:06:50 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 12:06:50 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 12:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:06:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:06:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:06:52 INFO - Installing collected packages: psutil 12:06:52 INFO - Running setup.py install for psutil 12:06:52 INFO - building 'psutil._psutil_linux' extension 12:06:52 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 12:06:52 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 12:06:52 INFO - building 'psutil._psutil_posix' extension 12:06:52 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 12:06:52 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 12:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:06:53 INFO - Successfully installed psutil 12:06:53 INFO - Cleaning up... 12:06:53 INFO - Return code: 0 12:06:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:06:53 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')]} 12:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:53 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')]} 12:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:53 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:06:53 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 12:06:53 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 12:06:53 INFO - Using env: {'DISPLAY': ':0', 12:06:53 INFO - 'HOME': '/home/cltbld', 12:06:53 INFO - 'LANG': 'en_US.UTF-8', 12:06:53 INFO - 'LANGUAGE': 'en_US:en', 12:06:53 INFO - 'LOGNAME': 'cltbld', 12:06:53 INFO - 'MAIL': '/var/mail/cltbld', 12:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:53 INFO - 'MOZ_NO_REMOTE': '1', 12:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:53 INFO - 'NO_EM_RESTART': '1', 12:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:53 INFO - 'PWD': '/builds/slave/test', 12:06:53 INFO - 'SHELL': '/bin/bash', 12:06:53 INFO - 'SHLVL': '1', 12:06:53 INFO - 'TERM': 'linux', 12:06:53 INFO - 'TMOUT': '86400', 12:06:53 INFO - 'USER': 'cltbld', 12:06:53 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:53 INFO - '_': '/tools/buildbot/bin/python'} 12:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:06:53 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 12:06:53 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 12:06:53 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 12:06:53 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 12:06:53 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 12:06:53 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 12:06:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:06:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:06:55 INFO - Installing collected packages: mozsystemmonitor 12:06:55 INFO - Running setup.py install for mozsystemmonitor 12:06:55 INFO - Successfully installed mozsystemmonitor 12:06:55 INFO - Cleaning up... 12:06:55 INFO - Return code: 0 12:06:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:06:55 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')]} 12:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:55 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')]} 12:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:55 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:06:55 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 12:06:55 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 12:06:55 INFO - Using env: {'DISPLAY': ':0', 12:06:55 INFO - 'HOME': '/home/cltbld', 12:06:55 INFO - 'LANG': 'en_US.UTF-8', 12:06:55 INFO - 'LANGUAGE': 'en_US:en', 12:06:55 INFO - 'LOGNAME': 'cltbld', 12:06:55 INFO - 'MAIL': '/var/mail/cltbld', 12:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:55 INFO - 'MOZ_NO_REMOTE': '1', 12:06:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:55 INFO - 'NO_EM_RESTART': '1', 12:06:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:55 INFO - 'PWD': '/builds/slave/test', 12:06:55 INFO - 'SHELL': '/bin/bash', 12:06:55 INFO - 'SHLVL': '1', 12:06:55 INFO - 'TERM': 'linux', 12:06:55 INFO - 'TMOUT': '86400', 12:06:55 INFO - 'USER': 'cltbld', 12:06:55 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:55 INFO - '_': '/tools/buildbot/bin/python'} 12:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:55 INFO - Downloading/unpacking jsonschema==2.5.1 12:06:55 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 12:06:55 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 12:06:55 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 12:06:55 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 12:06:55 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 12:06:55 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 12:06:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:06:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:06:57 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 12:06:57 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 12:06:57 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 12:06:57 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 12:06:57 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 12:06:57 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 12:06:57 INFO - Downloading functools32-3.2.3-2.tar.gz 12:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:06:57 INFO - warning: no files found matching '*.txt' 12:06:57 INFO - no previously-included directories found matching 'build' 12:06:57 INFO - no previously-included directories found matching 'dist' 12:06:57 INFO - no previously-included directories found matching '.git*' 12:06:57 INFO - Installing collected packages: jsonschema, functools32 12:06:57 INFO - Running setup.py install for functools32 12:06:57 INFO - warning: no files found matching '*.txt' 12:06:57 INFO - no previously-included directories found matching 'build' 12:06:57 INFO - no previously-included directories found matching 'dist' 12:06:57 INFO - no previously-included directories found matching '.git*' 12:06:57 INFO - Successfully installed jsonschema functools32 12:06:57 INFO - Cleaning up... 12:06:57 INFO - Return code: 0 12:06:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:06:57 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')]} 12:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:57 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')]} 12:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:06:57 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:06:57 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 12:06:57 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 12:06:57 INFO - Using env: {'DISPLAY': ':0', 12:06:57 INFO - 'HOME': '/home/cltbld', 12:06:57 INFO - 'LANG': 'en_US.UTF-8', 12:06:57 INFO - 'LANGUAGE': 'en_US:en', 12:06:57 INFO - 'LOGNAME': 'cltbld', 12:06:57 INFO - 'MAIL': '/var/mail/cltbld', 12:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:57 INFO - 'MOZ_NO_REMOTE': '1', 12:06:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:57 INFO - 'NO_EM_RESTART': '1', 12:06:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:57 INFO - 'PWD': '/builds/slave/test', 12:06:57 INFO - 'SHELL': '/bin/bash', 12:06:57 INFO - 'SHLVL': '1', 12:06:57 INFO - 'TERM': 'linux', 12:06:57 INFO - 'TMOUT': '86400', 12:06:57 INFO - 'USER': 'cltbld', 12:06:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:57 INFO - '_': '/tools/buildbot/bin/python'} 12:06:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:58 INFO - Downloading/unpacking blobuploader==1.2.4 12:06:58 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 12:06:58 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 12:06:58 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 12:06:58 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 12:06:58 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 12:06:58 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 12:07:00 INFO - Downloading blobuploader-1.2.4.tar.gz 12:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:07:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:07:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 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 12:07:00 INFO - Downloading docopt-0.6.1.tar.gz 12:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:07:01 INFO - Installing collected packages: blobuploader, requests, docopt 12:07:01 INFO - Running setup.py install for blobuploader 12:07:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:07:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:07:01 INFO - Running setup.py install for requests 12:07:01 INFO - Running setup.py install for docopt 12:07:01 INFO - Successfully installed blobuploader requests docopt 12:07:01 INFO - Cleaning up... 12:07:01 INFO - Return code: 0 12:07:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:01 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:07:01 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 12:07:01 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 12:07:01 INFO - Using env: {'DISPLAY': ':0', 12:07:01 INFO - 'HOME': '/home/cltbld', 12:07:01 INFO - 'LANG': 'en_US.UTF-8', 12:07:01 INFO - 'LANGUAGE': 'en_US:en', 12:07:01 INFO - 'LOGNAME': 'cltbld', 12:07:01 INFO - 'MAIL': '/var/mail/cltbld', 12:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:01 INFO - 'MOZ_NO_REMOTE': '1', 12:07:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:01 INFO - 'NO_EM_RESTART': '1', 12:07:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:01 INFO - 'PWD': '/builds/slave/test', 12:07:01 INFO - 'SHELL': '/bin/bash', 12:07:01 INFO - 'SHLVL': '1', 12:07:01 INFO - 'TERM': 'linux', 12:07:01 INFO - 'TMOUT': '86400', 12:07:01 INFO - 'USER': 'cltbld', 12:07:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:01 INFO - '_': '/tools/buildbot/bin/python'} 12:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:01 INFO - Running setup.py (path:/tmp/pip-g7l7Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:01 INFO - Running setup.py (path:/tmp/pip-RfJAPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:01 INFO - Running setup.py (path:/tmp/pip-b6YesL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:02 INFO - Running setup.py (path:/tmp/pip-gjfS7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:02 INFO - Running setup.py (path:/tmp/pip-HKlpqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:02 INFO - Running setup.py (path:/tmp/pip-_8ATOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:02 INFO - Running setup.py (path:/tmp/pip-zWtehy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:02 INFO - Running setup.py (path:/tmp/pip-rMoqUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:02 INFO - Running setup.py (path:/tmp/pip-UO0cdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:02 INFO - Running setup.py (path:/tmp/pip-XsmSR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:02 INFO - Running setup.py (path:/tmp/pip-EeUgBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:02 INFO - Running setup.py (path:/tmp/pip-tPuWx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:02 INFO - Running setup.py (path:/tmp/pip-UKHSRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:02 INFO - Running setup.py (path:/tmp/pip-_svkpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:02 INFO - Running setup.py (path:/tmp/pip-rl77fY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:02 INFO - Running setup.py (path:/tmp/pip-LK8QUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:02 INFO - Running setup.py (path:/tmp/pip-wZ6Zpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:07:03 INFO - Running setup.py install for manifestparser 12:07:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Running setup.py install for mozcrash 12:07:03 INFO - Running setup.py install for mozdebug 12:07:03 INFO - Running setup.py install for mozdevice 12:07:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Running setup.py install for mozfile 12:07:03 INFO - Running setup.py install for mozhttpd 12:07:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Running setup.py install for mozinfo 12:07:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Running setup.py install for mozInstall 12:07:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:07:03 INFO - Running setup.py install for mozleak 12:07:03 INFO - Running setup.py install for mozlog 12:07:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Running setup.py install for moznetwork 12:07:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Running setup.py install for mozprocess 12:07:04 INFO - Running setup.py install for mozprofile 12:07:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Running setup.py install for mozrunner 12:07:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Running setup.py install for mozscreenshot 12:07:04 INFO - Running setup.py install for moztest 12:07:04 INFO - Running setup.py install for mozversion 12:07:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:07:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:07:04 INFO - Cleaning up... 12:07:04 INFO - Return code: 0 12:07:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:04 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:07:04 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 12:07:04 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 12:07:04 INFO - Using env: {'DISPLAY': ':0', 12:07:04 INFO - 'HOME': '/home/cltbld', 12:07:04 INFO - 'LANG': 'en_US.UTF-8', 12:07:04 INFO - 'LANGUAGE': 'en_US:en', 12:07:04 INFO - 'LOGNAME': 'cltbld', 12:07:04 INFO - 'MAIL': '/var/mail/cltbld', 12:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:04 INFO - 'MOZ_NO_REMOTE': '1', 12:07:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:04 INFO - 'NO_EM_RESTART': '1', 12:07:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:04 INFO - 'PWD': '/builds/slave/test', 12:07:04 INFO - 'SHELL': '/bin/bash', 12:07:04 INFO - 'SHLVL': '1', 12:07:04 INFO - 'TERM': 'linux', 12:07:04 INFO - 'TMOUT': '86400', 12:07:04 INFO - 'USER': 'cltbld', 12:07:04 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:04 INFO - '_': '/tools/buildbot/bin/python'} 12:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:05 INFO - Running setup.py (path:/tmp/pip-pZumC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:05 INFO - Running setup.py (path:/tmp/pip-9422XL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:05 INFO - Running setup.py (path:/tmp/pip-XNsZ5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:05 INFO - Running setup.py (path:/tmp/pip-clSDP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:05 INFO - Running setup.py (path:/tmp/pip-rA7SVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:05 INFO - Running setup.py (path:/tmp/pip-oE5Cqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:05 INFO - Running setup.py (path:/tmp/pip-G1sv7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:05 INFO - Running setup.py (path:/tmp/pip-TsawIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:05 INFO - Running setup.py (path:/tmp/pip-sJxUCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:05 INFO - Running setup.py (path:/tmp/pip-FxKXhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:05 INFO - Running setup.py (path:/tmp/pip-m4AVX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:05 INFO - Running setup.py (path:/tmp/pip-MywnrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:05 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)) 12:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:05 INFO - Running setup.py (path:/tmp/pip-YqnEv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:06 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)) 12:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:06 INFO - Running setup.py (path:/tmp/pip-mVr3Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:06 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)) 12:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:06 INFO - Running setup.py (path:/tmp/pip-Z0yx1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:06 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)) 12:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:06 INFO - Running setup.py (path:/tmp/pip-69A2Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:06 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)) 12:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:06 INFO - Running setup.py (path:/tmp/pip-K3vERr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:06 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)) 12:07:06 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)) 12:07:06 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)) 12:07:06 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)) 12:07:06 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)) 12:07:06 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)) 12:07:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:07:06 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 12:07:06 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 12:07:06 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 12:07:06 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 12:07:06 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 12:07:06 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 12:07:08 INFO - Downloading blessings-1.6.tar.gz 12:07:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:07:08 INFO - Installing collected packages: blessings 12:07:08 INFO - Running setup.py install for blessings 12:07:08 INFO - Successfully installed blessings 12:07:08 INFO - Cleaning up... 12:07:08 INFO - Return code: 0 12:07:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:07:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:08 INFO - Reading from file tmpfile_stdout 12:07:08 INFO - Current package versions: 12:07:08 INFO - argparse == 1.2.1 12:07:08 INFO - blessings == 1.6 12:07:08 INFO - blobuploader == 1.2.4 12:07:08 INFO - docopt == 0.6.1 12:07:08 INFO - functools32 == 3.2.3-2 12:07:08 INFO - jsonschema == 2.5.1 12:07:08 INFO - manifestparser == 1.1 12:07:08 INFO - mozInstall == 1.12 12:07:08 INFO - mozcrash == 0.17 12:07:08 INFO - mozdebug == 0.1 12:07:08 INFO - mozdevice == 0.48 12:07:08 INFO - mozfile == 1.2 12:07:08 INFO - mozhttpd == 0.7 12:07:08 INFO - mozinfo == 0.9 12:07:08 INFO - mozleak == 0.1 12:07:08 INFO - mozlog == 3.2 12:07:08 INFO - moznetwork == 0.27 12:07:08 INFO - mozprocess == 0.22 12:07:08 INFO - mozprofile == 0.28 12:07:08 INFO - mozrunner == 6.11 12:07:08 INFO - mozscreenshot == 0.1 12:07:08 INFO - mozsystemmonitor == 0.3 12:07:08 INFO - moztest == 0.7 12:07:08 INFO - mozversion == 1.4 12:07:08 INFO - psutil == 3.1.1 12:07:08 INFO - requests == 1.2.3 12:07:08 INFO - wsgiref == 0.1.2 12:07:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:08 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:07:08 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 12:07:08 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 12:07:08 INFO - Using env: {'DISPLAY': ':0', 12:07:08 INFO - 'HOME': '/home/cltbld', 12:07:08 INFO - 'LANG': 'en_US.UTF-8', 12:07:08 INFO - 'LANGUAGE': 'en_US:en', 12:07:08 INFO - 'LOGNAME': 'cltbld', 12:07:08 INFO - 'MAIL': '/var/mail/cltbld', 12:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:08 INFO - 'MOZ_NO_REMOTE': '1', 12:07:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:08 INFO - 'NO_EM_RESTART': '1', 12:07:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:08 INFO - 'PWD': '/builds/slave/test', 12:07:08 INFO - 'SHELL': '/bin/bash', 12:07:08 INFO - 'SHLVL': '1', 12:07:08 INFO - 'TERM': 'linux', 12:07:08 INFO - 'TMOUT': '86400', 12:07:08 INFO - 'USER': 'cltbld', 12:07:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:08 INFO - '_': '/tools/buildbot/bin/python'} 12:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 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)) 12:07:08 INFO - Cleaning up... 12:07:08 INFO - Return code: 0 12:07:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:08 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 0x26c5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28288a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2986180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', '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 12:07:08 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 12:07:08 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 12:07:08 INFO - Using env: {'DISPLAY': ':0', 12:07:08 INFO - 'HOME': '/home/cltbld', 12:07:08 INFO - 'LANG': 'en_US.UTF-8', 12:07:08 INFO - 'LANGUAGE': 'en_US:en', 12:07:08 INFO - 'LOGNAME': 'cltbld', 12:07:08 INFO - 'MAIL': '/var/mail/cltbld', 12:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:08 INFO - 'MOZ_NO_REMOTE': '1', 12:07:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:08 INFO - 'NO_EM_RESTART': '1', 12:07:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:08 INFO - 'PWD': '/builds/slave/test', 12:07:08 INFO - 'SHELL': '/bin/bash', 12:07:08 INFO - 'SHLVL': '1', 12:07:08 INFO - 'TERM': 'linux', 12:07:08 INFO - 'TMOUT': '86400', 12:07:08 INFO - 'USER': 'cltbld', 12:07:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:08 INFO - '_': '/tools/buildbot/bin/python'} 12:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:07:09 INFO - Cleaning up... 12:07:09 INFO - Return code: 0 12:07:09 INFO - Running post-action listener: _resource_record_post_action 12:07:09 INFO - Running post-action listener: _start_resource_monitoring 12:07:09 INFO - Starting resource monitoring. 12:07:09 INFO - ##### 12:07:09 INFO - ##### Finished create-virtualenv step (success) 12:07:09 INFO - ##### 12:07:09 INFO - ##### 12:07:09 INFO - ##### Running install step. 12:07:09 INFO - ##### 12:07:09 INFO - Running pre-action listener: _resource_record_pre_action 12:07:09 INFO - Running main action method: install 12:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:09 INFO - Reading from file tmpfile_stdout 12:07:09 INFO - Detecting whether we're running mozinstall >=1.0... 12:07:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:07:09 INFO - Reading from file tmpfile_stdout 12:07:09 INFO - Output received: 12:07:09 INFO - Usage: mozinstall [options] installer 12:07:09 INFO - Options: 12:07:09 INFO - -h, --help show this help message and exit 12:07:09 INFO - -d DEST, --destination=DEST 12:07:09 INFO - Directory to install application into. [default: 12:07:09 INFO - "/builds/slave/test"] 12:07:09 INFO - --app=APP Application being installed. [default: firefox] 12:07:09 INFO - mkdir: /builds/slave/test/build/application 12:07:09 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'] 12:07:09 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 12:07:25 INFO - Reading from file tmpfile_stdout 12:07:25 INFO - Output received: 12:07:25 INFO - /builds/slave/test/build/application/firefox/firefox 12:07:25 INFO - Running post-action listener: _resource_record_post_action 12:07:25 INFO - ##### 12:07:25 INFO - ##### Finished install step (success) 12:07:25 INFO - ##### 12:07:25 INFO - ##### 12:07:25 INFO - ##### Running run-tests step. 12:07:25 INFO - ##### 12:07:25 INFO - Running pre-action listener: _resource_record_pre_action 12:07:25 INFO - Running main action method: run_tests 12:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:07:25 INFO - Python 2.7.3 12:07:25 INFO - Return code: 0 12:07:25 INFO - grabbing minidump binary from tooltool 12:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:07:25 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 0x29843b0>, '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 0x29852b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2985740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:07:25 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 12:07:25 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 12:07:25 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 12:07:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:07:25 INFO - Return code: 0 12:07:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:07:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:07:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:07:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:07:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:07:25 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/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 12:07:25 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/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 12:07:25 INFO - Using env: {'DISPLAY': ':0', 12:07:25 INFO - 'HOME': '/home/cltbld', 12:07:25 INFO - 'LANG': 'en_US.UTF-8', 12:07:25 INFO - 'LANGUAGE': 'en_US:en', 12:07:25 INFO - 'LOGNAME': 'cltbld', 12:07:25 INFO - 'MAIL': '/var/mail/cltbld', 12:07:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:07:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:25 INFO - 'MOZ_NO_REMOTE': '1', 12:07:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:07:25 INFO - 'NO_EM_RESTART': '1', 12:07:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:25 INFO - 'PWD': '/builds/slave/test', 12:07:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:07:25 INFO - 'SHELL': '/bin/bash', 12:07:25 INFO - 'SHLVL': '1', 12:07:25 INFO - 'TERM': 'linux', 12:07:25 INFO - 'TMOUT': '86400', 12:07:25 INFO - 'USER': 'cltbld', 12:07:25 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132', 12:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:25 INFO - '_': '/tools/buildbot/bin/python'} 12:07:25 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/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 12:07:25 INFO - mozversion application_buildid: 20160630105729 12:07:25 INFO - mozversion application_changeset: 8d2952ce50f321c39cc2bd8a2c65deb322e5e132 12:07:25 INFO - mozversion application_display_name: Nightly 12:07:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:07:25 INFO - mozversion application_name: Firefox 12:07:25 INFO - mozversion application_remotingname: firefox 12:07:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:07:25 INFO - mozversion application_vendor: Mozilla 12:07:25 INFO - mozversion application_version: 50.0a1 12:07:25 INFO - mozversion platform_buildid: 20160630105729 12:07:25 INFO - mozversion platform_changeset: 8d2952ce50f321c39cc2bd8a2c65deb322e5e132 12:07:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:07:25 INFO - mozversion platform_version: 50.0a1 12:07:26 INFO - using testdate: 1467313645 12:07:26 INFO - actual date: 1467313645 12:07:26 INFO - starting webserver on 'localhost:35743' 12:07:26 INFO - SUITE-START | Running 2 tests 12:07:26 INFO - TEST-START | dromaeo_css 12:07:26 INFO - operating with platform_type : linux_ 12:07:26 INFO - Initialising browser for dromaeo_css test... 12:07:26 INFO - TEST-INFO | started process 27270 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUEkg9E/profile http://localhost:35743/getInfo.html) 12:07:30 INFO - PROCESS | 27270 | __metrics Screen width/height:1600/1200 12:07:30 INFO - PROCESS | 27270 | colorDepth:24 12:07:30 INFO - PROCESS | 27270 | Browser inner width/height: 1024/697 12:07:30 INFO - PROCESS | 27270 | __metrics 12:07:31 INFO - PROCESS | 27270 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:07:32 INFO - TEST-INFO | 27270: exit 0 12:07:32 INFO - Browser initialized. 12:07:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:07:32 INFO - TEST-INFO | started process 27350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUEkg9E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:07:44 INFO - PROCESS | 27350 | RSS: Main: 159420416 12:07:44 INFO - PROCESS | 27350 | 12:08:35 INFO - PROCESS | 27350 | 1467313715144 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] 12:10:47 INFO - PROCESS | 27350 | Cycle 1(1): loaded http://localhost:35743/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35743/tests/dromaeo/cssquery-ext.html) 12:10:49 INFO - PROCESS | 27350 | RSS: Main: 273518592 12:10:49 INFO - PROCESS | 27350 | 12:13:52 INFO - PROCESS | 27350 | Cycle 1(1): loaded http://localhost:35743/tests/dromaeo/cssquery-ext.html (next: http://localhost:35743/tests/dromaeo/cssquery-jquery.html) 12:13:52 INFO - PROCESS | 27350 | RSS: Main: 238624768 12:13:52 INFO - PROCESS | 27350 | 12:16:55 INFO - PROCESS | 27350 | Cycle 1(1): loaded http://localhost:35743/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35743/tests/dromaeo/cssquery-mootools.html) 12:16:55 INFO - PROCESS | 27350 | RSS: Main: 243355648 12:16:55 INFO - PROCESS | 27350 | 12:19:57 INFO - PROCESS | 27350 | Cycle 1(1): loaded http://localhost:35743/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35743/tests/dromaeo/cssquery-prototype.html) 12:19:58 INFO - PROCESS | 27350 | RSS: Main: 234110976 12:19:58 INFO - PROCESS | 27350 | 12:23:01 INFO - PROCESS | 27350 | Cycle 1(1): loaded http://localhost:35743/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35743/tests/dromaeo/cssquery-yui.html) 12:23:02 INFO - PROCESS | 27350 | RSS: Main: 219541504 12:23:02 INFO - PROCESS | 27350 | 12:26:04 INFO - PROCESS | 27350 | Cycle 1(1): loaded http://localhost:35743/tests/dromaeo/cssquery-yui.html (next: http://localhost:35743/tests/dromaeo/cssquery-dojo.html) 12:26:05 INFO - PROCESS | 27350 | RSS: Main: 262770688 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | __start_tp_report 12:26:05 INFO - PROCESS | 27350 | _x_x_mozilla_page_load 12:26:05 INFO - PROCESS | 27350 | _x_x_mozilla_page_load_details 12:26:05 INFO - PROCESS | 27350 | |i|pagename|runs| 12:26:05 INFO - PROCESS | 27350 | |0;dojo.html;2621.580547112462;2628.8817003234853;2668.132856821533;2678.357194273375;2680.222735406732;4620.537946205378;4697.624190064791;4721.78751956162;4766.237402015674;4819.951800481998;4744.715317080969;4751.12104260712;4784.784684689189;4788.784504697288;4792.04159168166;4343.761093139876;4350.956490435103;4408.911821763579;4439.356293337464;4541.159885421192;4439.223135951195;4475.7762111894435;4487.483939346968;4488.394066800973;4518.593326600605;4279.935800962988;4321.956780432219;4378.277584198603;4413.492448368446;4419.845305414295;4387.780610969438;4390.078083602415;4393.143337049277;4444.933326000112;4449.26587113126;4399.7360158390275;4417.2269852775635;4421.182081314967;4452.510223875371;4463.553644635543;4652.581267685924;4782.282657601353;4788.784504697297;4841.685290456109;4850.029994001214;5189.221616757479;5202.193659982709;5204.609654275944;5205.297284866532;5205.505476979681;5145.922811157836;5180.818671346522;5217.139172036609;5231.92745487178;5251.711155886424;4680.3681502997;4790.640701947368;4797.544233297831;4833.589144922721;4886.902261954741;5324.307839980777;5346.304980352529;5348.331458567679;5349.1976203569;5351.919721204185;4839.685420447659;5058.519440653132;5108.259302401131;5112.642115051911;5120.667156634807;5154.639175257696;5205.06308864408;5206.427292997767;5213.895722085537;5222.608304377187;5165.638405311592;5175.611829112831;5175.715335656575;5178.766955487012;5229.111051121318;4936.48166942473;5050.671706339076;5147.922781158286;5160.819371322061;5168.9741551292;4371.803268852909;4377.715448495837;4391.912161756747;4432.3794668746405;4440.7335559866515;3800.923981520354;3808.3906574947873;3824.2351529694174;3853.0174805424435;3858.594847541024;4285.892852678646;4328.675349348811;4382.890427739332;4422.115576884636;4427.490838553543;5174.14626586609;5174.456682048406;5194.22086687;5204.297419848348;5251.474852514718;4433.357163211316;4437.378766972626;4440.666949978765;4445.844395446144;4474.686771925999;4534.59188673021;4571.748553829571;4666.883327916829;4670.299455081765;4713.74074425903;4854.8786280342565;4893.266010098514;4921.9015619688125;4922.064807686528;4946.554810067111;5243.344581927259;5244.921326180034;5254.500822421864;5258.7107709074835;5304.920426193533;4711.952880471152;4836.95163048365;4895.534924182197;4900.264960255848;4908.165611845923;5018.971110922325;5051.4443411225475;5057.31726216956;5062.392512898476;5068.594512438923;5127.512886275797;5129.948700512947;5131.153359695609;5133.4096578893905;5192.532672059533;4840.177169881199;4990.476000019946;5002.774875130555;5006.874828129326;5016.021875734197;5040.49595040493;5059.696418214919;5061.924071139009;5081.136206844919;5115.565176959852;5008.198688209869;5021.322121513549;5021.974890125526;5071.492850714899;5077.8730531737;4883.560479556715;4936.160852655129;4963.057019166346;5109.029284435946;5155.304033955368;4860.659753817199;4884.682495637772;4887.731174785279;4930.408350997904;4942.777575009205 12:26:05 INFO - PROCESS | 27350 | |1;ext.html;540.0319879574748;584.5119325363321;590.052964991189;598.5152026858242;602.3404372212428;27185;39791;40699.59300406995;40894;40924.79537602308;40039.79980100103;40430.59569404305;40441.19117617646;40544.39183412244;40575.797121014424;7568.508046976929;7790.727324543642;7791.4935529190425;7939.801504962379;8098.190180981884;7672.769816905474;10048.296619236657;10308.587656493732;10312.896871031306;10656.520456579434;10320.845187322197;10393.272470927039;10393.37639741618;10407.479626018707;10407.53166107527;7648.38812894967;10165.542550585167;10310.536025878815;10367.37795732251;10369.792604147913;9912.851307230398;10196.286259961806;10205.48972551377;10206;10209.336393134423;38489;38781.224375512335;38858.61141388578;38871.8056409719;38901;3191.3138675184946;3193.409219294438;3398.609159946613;3708.740388172802;3802.8478860845535;27557.586636200474;29304;29456.8527157365;29572.70427295721;30423.847880760455;3276.6723327667282;3357.61387440445;3367.8652853885815;3372.932541349184;3392.1858753899132;273674;274331;275260;275501;276154.6192269046;27924.860375698398;47749.522504774504;48084.5191548087;48157;48253.51746482561;33813.830930845186;33901.49147762785;33951.83024084913;33993;34004.97985060452;13113.475460981645;13243.470261189637;13243.536476223286;13292.800607990888;13307.800382994263;9713.514324283899;10664.520096595672;10669.466526673636;10773.83839242412;10972.670819875417;7787.260210280015;7857.489263197874;7869.331106855867;7911.129775724666;7934.127246002935;3090.629124505074;3091.381723655278;3099.7136188481527;3115.6572776994512;3125.24994001438;873.323174539724;882.571952602987;903.4172958441768;918.1598837064012;1019.6533178719271;26612.201633951012;26873.193804185907;27622;27656.446871062468;27712.168634940983;963.1716723617683;1005.8038682456851;1034.3018462537757;1062.6227689170232;1075.2365820267698;14813.185274809988;14837.777433338508;14863.405463781328;15138;15204.619884502754;401933;409605;414523;416983;577107;48831.26753098706;49051.26423103656;49259.75370123198;49338.50661493411;49413;1096.1066730614523;1203.4524291447547;1229.170310040737;1232.8212409200617;1242.0250103668577;24802.875985620678;28470.43059138847;28770.28074298159;28836;28863;40591.1881762369;40940;41234.175316494104;41321.586784131774;41341.37987930123;308547;310959;311171;311607;312073;22513.549729005656;24458.75541244636;25495;25530.23409297724;25564.360890977874;40410.797946011255;40813.91860813895;41356.1728765417;41544.376834346906;42338.788306058275;35477;35505;36149.63850361568;36461.45307820332;36607.45088823616;35882.4617630741;35949.46075808813;36256;36496.81751591331;36678.8166059168;34399;34952.300953980266;34969;35325.293494130485;35556;30736.538951916187;32099.197520062186;32662.83668581642;32704.83647581747;32778.508322374706;24539.509209815347;24986.250412487654;25120.246392608253;25316.493670126863;25459.872700636377 12:26:05 INFO - PROCESS | 27350 | |2;jquery.html;342.98285085745704;371.06676708079186;372.7223218702069;376.7287552961868;387.50205985308867;12974.61076167714;17186.5703357416;17945.82054179458;18342.81657183428;18346.26614935401;16768.58078548034;17518.649627007453;17969.91015044926;17970.820291797078;17982.730259046093;4876.487968763291;5312.256284120207;5315.202719592073;5315.734213289339;5317.468253174671;3663.3955397359405;5181.35233095863;5837.708114594275;6143.754249830002;6157.568970172068;6173.536984726141;6187.133801267826;6201.875962480748;6211.40991605799;6215.627062376249;5034.295198672189;5260.92108618371;5264.447233040514;5336.9733151334385;5351.785928562853;3361.3109312591105;4980.551750342486;5153.3815942087185;5303.893922121574;5330.5735541157;12511.436985335591;12655.936720316431;12766.744665106646;15305;21384.14463421477;1793.6323053773945;1916.0611300462813;1961.9509512262166;2018.7173795668618;2472.208893154191;9466.715998520056;9744.610215591369;9764.170045546138;11704.005159561448;12624.68438289041;1747.860171186302;1810.6650269700147;1853.8146185381488;1861.1159699142934;1866.2348437140843;65588.3441165587;65955.67022164907;68521.31478685199;69268;74536.25463745401;8786.86819697705;9553.42679439222;10049.949750251202;10113.027959547964;10182.643607473703;12556.81164782529;13751.587452376445;13807.378667959965;14236.28818559068;14247.928760356339;6803.897941530881;6959.668511048754;6997.685104170325;7024.789256322319;7096.574205547684;6527;6534.934650653528;6566.540342176002;6667.566608170453;6794.694238759268;4440.511543730187;4441.9333709993725;4445.155420470101;4469.41897553316;4510.639148868083;1794.2052558837963;1845.3264558436074;1849.6115815678588;1867.8599105067174;1901.7970285668828;781.8944442500303;870.8171284030298;911.2937473458123;921.4977837078808;932.4172392254841;17039.880890137243;17177.742333865;17756;17840.821591783915;17884.55288617795;868.6525389844113;873.1966590736538;876.7150676030316;887.5917078143997;887.7603047177226;9482.288828337902;9711.125998660154;9751.512424378752;9973.850392244121;10181.54183061764;367509;367658;382659;384657;385071;51150;51418.228726569134;66489;80945.59527202326;81872.59063704644;871.0723079919992;885.5218182181678;889.5374405309203;899.4378513429107;901.1799262670938;15489.5353139406;15572.454964076656;15722.21388930526;15934.840651593335;16238.269277882287;36761.448578270814;38090.8095459532;39102.78806512514;39115.41326880156;39991.000224994605;50159.74920125376;52506;53092.20361694655;53250;53991;7400.333969942731;7636.198199189005;7667.769966901002;7692.7692169235015;7902.525848449111;9843.507824608598;11600;11709.180357374902;11788.115891308358;12059.698507537381;10001.399916005063;10042.397456152654;10087.69736907894;10113.646022389476;10238.948805255926;10134.594616215269;10340.27618066728;10359;10445.738856528647;10460.21548383859;9018.954905225432;9759.707208783464;10064.446455444728;10199.28604997643;10533.4733263335;7358.411327093927;7603.7338693145475;7741.95483609728;7767.300942914939;7897.93377894;8743.868841967504;9989.750256243651;10130.290879638354;10230.846537301797;10330.741731456474 12:26:05 INFO - PROCESS | 27350 | |3;mootools.html;1205.240698360033;1346.165377465971;1419.74444599972;1476.4537121265134;1731.4459373000645;6602.1417215762;7503.774886753399;7777;7786.571738554391;7787.766367008991;7511.43664225184;7758.185390533998;7771.455998080137;7935.087464941534;7935.880961785578;1912.0726447672866;2159.730033745782;2190.2005767894725;2226.0428015953134;2242.551489702058;2226.2430773536994;2230.698855654482;2232.386093824195;2235.508188198594;2237.1163390460756;2143.3891340967825;2169.6745488176743;2212.811910987568;2212.9557408851815;2220.245116660335;1850.2598960415808;1913.4833594929291;1916.6741653918866;2101.2540548105353;2200.9559808803815;2209.569134018867;2230.6877037214804;2236;2237.071615279657;2242.5627002734473;7822.921770782333;7883.054033515958;7883.645235964395;7924.286814186693;7976.6809327626825;4343.543927887589;4421.292593185107;4459.710118842265;4465.531119232466;4469.843555475575;2848.2879280179955;2901.738843504095;2963.777716671258;2968.0650595062484;2970.4504666636526;905.9320550958704;925.4771054354268;933.0389698610442;953.5232383808096;956.3305686019744;10774.35353878762;10931;10944.507497162647;10984.890151098467;11000.834987475195;4116.670666346685;4229.175310814423;4344.978275108668;4346.869593912188;4350.71720338177;9341.859872101924;9396.90603093974;9398.154166124945;9405.905940940504;9409.905900940903;4685.9765701171955;4687.648426368036;4688.531146885284;4688.906221875612;4694.0611877624615;3671.4125739881492;4030.314846476106;4196.91606167881;4220.134872351161;4254.106637606075;3659.579148397915;3687.2441149564274;3757.1358587525024;3785.09157802131;3818.961810381916;1069.2301542889263;1152.176194021262;1181.2381014245764;1188.292965685433;1205.1744554979866;676.2459857259121;698.9755358562426;733.9706411743578;787.5392895156398;813.32494029955;5232.476752324813;5339.492748188916;5369.785208591691;5401.054815407288;5438.048341540215;870.7518357268148;876.5748611923212;883.9878339301464;894.6913314906346;913.7167478081816;3599.5500562429697;3662.61542538035;3709.8330575124182;3754.2491501699224;3800.467934489174;9726.70819875405;9976.102150806462;10029.297949143636;10050.296479246528;10052.798944021079;8969.14793094655;9950.154236889946;9968.80062398748;9989.750256243507;9992.850107248398;7128.002079708845;7348.77953661391;7503.324700776956;7513.699452021858;7515.549067055994;5367.221752845893;5502.257195278747;5634;5667.489925906688;5786.074228123648;8728.170823771732;8992.28061755071;9061.093890610886;9145.085491450802;9233.122853328923;9061.501617411106;9259.67591134334;9349.532523373668;9392.248620110511;9525.666601668909;6774.153230846145;6958.79123626292;6985.231624521197;7109.502334836719;7146.571205727673;7830.530168189927;7844.411669124953;7892.329152022143;7896.170902055431;7911.327537159406;6890;6986.301369862973;7061.505694601328;7106.786796396116;7128.28717128283;6946.270641582561;6975.093237879043;7000.684969176501;7071.434285257064;7091.078159839188;5787.102999035156;5797.043487824461;5821;5863.589548731547;5867.706614669164;4363.912721745611;4388.4733831940375;4433.401490798701;4442.622377097983;4557.521460246759;4473.865784026484;4557.635389168792;4562.406887104655;4618.676692631618;4659.650526210616 12:26:05 INFO - PROCESS | 27350 | |4;prototype.html;279.7244713956753;291.1861347533644;293.73710529076476;296.2682175027681;296.9020223326303;4622.791974361154;4707.858764237069;4795.808167673294;4836.927446088307;4836.927446088307;4584.633229341653;4711.8455978285365;4721.268203428465;4725.6928299660585;4740.407449068866;3751.6623503884643;3758.2483503299313;3766.811659417032;3774.1508160663907;3776.3957766757326;3587.408077667195;3644.5079914211506;3763.8306276217636;3764.171882185915;3788.2234142000825;3703.1112532992024;3746.5316835395574;3754.4180651998986;3756.417755247941;3768.9057773555605;3751.118487155516;3754.1740817020213;3759.718021148411;3767.8492860285555;3778.2254637799183;3336.582927134108;3339.8497067632015;3340.16495876031;3348.648391918862;3349.363620912043;4567.246404343313;4627.907441851178;4649.279361698942;4649.465311489188;4680.602148817355;4238.237117318881;4255;4256.34026725858;4272.423222864904;4277.850275240383;4966.329545511346;4968.857162852564;4977.552020318189;4978.576820970222;4985.626078044161;3395.422778127724;3568.678818906311;3574.6067932527403;3638.381475149231;3648.7445878788494;5181.3782346118705;5230.581553475751;5247.606429517763;5251.947480525184;5263.815766448156;3588.982055089708;3610.548681414823;3615.439606860941;3630.546181727284;3636.5090712753968;3653.598104208535;3657.085728567858;3663.157473781045;3667.504886840296;3673.1000904777966;3982.9402558961638;4226.725262857904;4227.35133298014;4231.111466591989;4245.532991370947;4700.177468942921;4934.383202099738;5024.84925452237;5040.319556859777;5042.344495215599;1229.440604524939;1259.3325537465157;1260.691130672991;1266.360487953596;1268;797.214743477674;799.8760192170223;818.333058557274;819.0376307838267;819.6311659753015;3961.9801900990888;3983.5817239190046;3986.60133986599;3992.5608183099835;3996.980015099906;4484.394606728115;4508.526604706524;4509.571590698878;4544.2047641662575;4565.863024109282;3901.51231096113;3921.313770090223;3982.2433737589763;4013.979930100331;4018.0668112277617;2348.8120950323932;2453.5338285725657;2463.7782599565767;2465.395977985405;2466.432720474265;4326.913461730748;4336.826526938887;4345.196138714348;4346.391835020999;4356.934645980313;4609.976950115295;4620.8151673933335;4640.23436133041;4663.790129444184;4680.95319046812;4058.411530328086;4280.186764514732;4300.677449191386;4302.806373713191;4303.096349766584;4523.864284071483;4546.477155127202;4554.453465584019;4556;4559.270516717309;4446.221911165469;4470.530594287683;4475.753833539186;4486.304622783474;4491.977540112279;4113.876583702494;4258.893527661833;4273.252180868273;4299.398084268142;4308.806103725403;3313.254517733587;3328.53400523922;3334.7999120052873;3336.6329703733054;3338.3657105149946;4514.90970180588;4523.32150177476;4540.818367265272;4548.840790572314;4914.164592019294;4755.714657120584;4998.6500944933505;5002.399712034579;5008.9248661269385;5015.899682006412;4992.850214493571;4995.350604421402;5001.64988450805;5002.949970500248;5010.624203184703;4810.302506136521;4928.2853986171085;4943.332650092191;4960.851174464772;4961.70229786214;4986.252062190701;5008.499150084962;5013.498650134958;5020.648554601143;5035.395752509722;4903.289023091703;4906.2640603909695;4945.777440015136;4977.925331120108;4978.5270399312 12:26:05 INFO - PROCESS | 27350 | |5;yui.html;650.3236633900742;668.3216535216756;682.5120039171991;684.3225207045027;747.439420434674;13091.14907531011;13974.3711532981;13981.231032293219;13991.93004034981;14018.789718154236;12106;13078.280694561796;13081.476740930375;13099.14855534392;13106.082574219809;617.3918690090258;632.7658766256492;779.0651218537763;786.6224212378062;794.2653045932518;795.9562224077673;801.5230937592153;803.5018288661009;804.3082948664144;805.7059173401695;842.0442797424905;848.6859861851124;850.4344610833788;850.4472093139447;850.706506255341;651.072222083529;796.5061661769682;827.5283088639418;843.5023336231623;844.0799528513919;611.1901730207449;614.2751553167261;614.3181069013392;614.7725341623583;614.8678034222663;13504.459821607124;13505.527306544223;13505.594832155051;13518.391672374766;13564.45742170312;12463.813042804366;13026.348682565835;13043.347832608426;13092.279924604143;13314.800277995837;11751.529938802438;11940.820887686692;12198.878011219951;12320.137590368679;12638.74722505554;4019.6382325590544;4045.2920738870876;4095.467589213413;4180.393842892763;4188.057687020396;61020.69489652523;61230.7753844925;61625.383746162865;61937;62041.6897915512;10483.842742358871;10917.617883374203;10957.726056848642;10972.725681858017;11015.28400653971;18086.819131808777;18118.45644630663;18147.818521814614;18155.636887262182;18162.909185453987;6718.49611279156;6884;6922.651189924174;7086.326798954091;7318.560886346836;3748.1754014117396;3752.6247375262255;3813.3326667833016;3815.236952609489;3821.178446633987;2755.641766570333;2842.459932612838;2843.232327271625;2843.957340639892;2844.3458004659046;508.98473045807947;514.1311184098861;518.2356024863371;525.821220784935;535.2640119835227;538.7171734839241;549.8130635583921;549.84879158231;554.7337278106531;567.4212303450457;7243.782686519413;7472.85054298911;7769.339606133542;8003.199680032067;8138.9186108138165;508.32000239209765;519.4545726986725;520.8177138001669;538.9326334208224;542.1352942057412;3579.302036102987;3668.284684486553;3727.3849814780256;3808.866689665904;3855.325318069327;76142.61928690321;79654.20345796584;80110.19889801144;90165;91993.54003230075;79878.20121798824;80005;81145.59427202826;81888.59055704683;81942;44514;46663.76668116706;72260.27739722641;72701.27298727051;75878.766140602;7294.489385743159;7529.661165247468;7564.205758395289;7899.526028438312;8039.5176289422825;31678.208044799063;37118.44322335113;41488.79255603703;42603.786981066136;45366.09267814559;62511.68744156432;62907;64563.67718161567;66336.33663663495;66444.66777666274;9041.231495322709;9147.0852914708;9148.954255228682;9209.539523023956;9245.260379169786;10393.272470926962;10459.424731639832;10546;10558.8416173759;10615.522301496298;7988.400869934894;11079.279846809932;11302.491387887729;11650.825237621611;11776.349331707199;10922.56309747633;11252.043576295779;11256.099512038854;11391.430428478709;11475.196736228718;11026.944865275622;11047;11049.116070714163;12311.815322769986;12327.075469340014;9079.319051071065;9353.298502612195;9544.665936692183;9547.618095276111;9556.092171243721;9373.25013998892;10060.69817905464;10116.393016419039;10219.846702299323;10252.231082668979 12:26:05 INFO - PROCESS | 27350 | __end_tp_report 12:26:05 INFO - PROCESS | 27350 | __start_cc_report 12:26:05 INFO - PROCESS | 27350 | _x_x_mozilla_cycle_collect,1133 12:26:05 INFO - PROCESS | 27350 | __end_cc_report 12:26:05 INFO - PROCESS | 27350 | __startTimestamp1467314765238__endTimestamp 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | ------- Summary: start ------- 12:26:05 INFO - PROCESS | 27350 | Number of tests: 6 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | [#0] dojo.html Cycles:165 Average:4763.74 Median:4886.90 stddev:514.12 (10.5%) stddev-sans-first:487.45 12:26:05 INFO - PROCESS | 27350 | Values: 2621.6 2628.9 2668.1 2678.4 2680.2 4620.5 4697.6 4721.8 4766.2 4820.0 4744.7 4751.1 4784.8 4788.8 4792.0 4343.8 4351.0 4408.9 4439.4 4541.2 4439.2 4475.8 4487.5 4488.4 4518.6 4279.9 4322.0 4378.3 4413.5 4419.8 4387.8 4390.1 4393.1 4444.9 4449.3 4399.7 4417.2 4421.2 4452.5 4463.6 4652.6 4782.3 4788.8 4841.7 4850.0 5189.2 5202.2 5204.6 5205.3 5205.5 5145.9 5180.8 5217.1 5231.9 5251.7 4680.4 4790.6 4797.5 4833.6 4886.9 5324.3 5346.3 5348.3 5349.2 5351.9 4839.7 5058.5 5108.3 5112.6 5120.7 5154.6 5205.1 5206.4 5213.9 5222.6 5165.6 5175.6 5175.7 5178.8 5229.1 4936.5 5050.7 5147.9 5160.8 5169.0 4371.8 4377.7 4391.9 4432.4 4440.7 3800.9 3808.4 3824.2 3853.0 3858.6 4285.9 4328.7 4382.9 4422.1 4427.5 5174.1 5174.5 5194.2 5204.3 5251.5 4433.4 4437.4 4440.7 4445.8 4474.7 4534.6 4571.7 4666.9 4670.3 4713.7 4854.9 4893.3 4921.9 4922.1 4946.6 5243.3 5244.9 5254.5 5258.7 5304.9 4712.0 4837.0 4895.5 4900.3 4908.2 5019.0 5051.4 5057.3 5062.4 5068.6 5127.5 5129.9 5131.2 5133.4 5192.5 4840.2 4990.5 5002.8 5006.9 5016.0 5040.5 5059.7 5061.9 5081.1 5115.6 5008.2 5021.3 5022.0 5071.5 5077.9 4883.6 4936.2 4963.1 5109.0 5155.3 4860.7 4884.7 4887.7 4930.4 4942.8 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | [#1] ext.html Cycles:180 Average:48293.32 Median:18068.06 stddev:93514.06 (517.6%) stddev-sans-first:93707.66 12:26:05 INFO - PROCESS | 27350 | Values: 540.0 584.5 590.1 598.5 602.3 27185.0 39791.0 40699.6 40894.0 40924.8 40039.8 40430.6 40441.2 40544.4 40575.8 7568.5 7790.7 7791.5 7939.8 8098.2 7672.8 10048.3 10308.6 10312.9 10656.5 10320.8 10393.3 10393.4 10407.5 10407.5 7648.4 10165.5 10310.5 10367.4 10369.8 9912.9 10196.3 10205.5 10206.0 10209.3 38489.0 38781.2 38858.6 38871.8 38901.0 3191.3 3193.4 3398.6 3708.7 3802.8 27557.6 29304.0 29456.9 29572.7 30423.8 3276.7 3357.6 3367.9 3372.9 3392.2 273674.0 274331.0 275260.0 275501.0 276154.6 27924.9 47749.5 48084.5 48157.0 48253.5 33813.8 33901.5 33951.8 33993.0 34005.0 13113.5 13243.5 13243.5 13292.8 13307.8 9713.5 10664.5 10669.5 10773.8 10972.7 7787.3 7857.5 7869.3 7911.1 7934.1 3090.6 3091.4 3099.7 3115.7 3125.2 873.3 882.6 903.4 918.2 1019.7 26612.2 26873.2 27622.0 27656.4 27712.2 963.2 1005.8 1034.3 1062.6 1075.2 14813.2 14837.8 14863.4 15138.0 15204.6 401933.0 409605.0 414523.0 416983.0 577107.0 48831.3 49051.3 49259.8 49338.5 49413.0 1096.1 1203.5 1229.2 1232.8 1242.0 24802.9 28470.4 28770.3 28836.0 28863.0 40591.2 40940.0 41234.2 41321.6 41341.4 308547.0 310959.0 311171.0 311607.0 312073.0 22513.5 24458.8 25495.0 25530.2 25564.4 40410.8 40813.9 41356.2 41544.4 42338.8 35477.0 35505.0 36149.6 36461.5 36607.5 35882.5 35949.5 36256.0 36496.8 36678.8 34399.0 34952.3 34969.0 35325.3 35556.0 30736.5 32099.2 32662.8 32704.8 32778.5 24539.5 24986.3 25120.2 25316.5 25459.9 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | [#2] jquery.html Cycles:180 Average:23648.17 Median:4457.29 stddev:62278.28 (1397.2%) stddev-sans-first:62428.41 12:26:05 INFO - PROCESS | 27350 | Values: 343.0 371.1 372.7 376.7 387.5 12974.6 17186.6 17945.8 18342.8 18346.3 16768.6 17518.6 17969.9 17970.8 17982.7 4876.5 5312.3 5315.2 5315.7 5317.5 3663.4 5181.4 5837.7 6143.8 6157.6 6173.5 6187.1 6201.9 6211.4 6215.6 5034.3 5260.9 5264.4 5337.0 5351.8 3361.3 4980.6 5153.4 5303.9 5330.6 12511.4 12655.9 12766.7 15305.0 21384.1 1793.6 1916.1 1962.0 2018.7 2472.2 9466.7 9744.6 9764.2 11704.0 12624.7 1747.9 1810.7 1853.8 1861.1 1866.2 65588.3 65955.7 68521.3 69268.0 74536.3 8786.9 9553.4 10049.9 10113.0 10182.6 12556.8 13751.6 13807.4 14236.3 14247.9 6803.9 6959.7 6997.7 7024.8 7096.6 6527.0 6534.9 6566.5 6667.6 6794.7 4440.5 4441.9 4445.2 4469.4 4510.6 1794.2 1845.3 1849.6 1867.9 1901.8 781.9 870.8 911.3 921.5 932.4 17039.9 17177.7 17756.0 17840.8 17884.6 868.7 873.2 876.7 887.6 887.8 9482.3 9711.1 9751.5 9973.9 10181.5 367509.0 367658.0 382659.0 384657.0 385071.0 51150.0 51418.2 66489.0 80945.6 81872.6 871.1 885.5 889.5 899.4 901.2 15489.5 15572.5 15722.2 15934.8 16238.3 36761.4 38090.8 39102.8 39115.4 39991.0 50159.7 52506.0 53092.2 53250.0 53991.0 7400.3 7636.2 7667.8 7692.8 7902.5 9843.5 11600.0 11709.2 11788.1 12059.7 10001.4 10042.4 10087.7 10113.6 10238.9 10134.6 10340.3 10359.0 10445.7 10460.2 9019.0 9759.7 10064.4 10199.3 10533.5 7358.4 7603.7 7742.0 7767.3 7897.9 8743.9 9989.8 10130.3 10230.8 10330.7 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | [#3] mootools.html Cycles:180 Average:5208.30 Median:5368.50 stddev:2965.69 (55.2%) stddev-sans-first:2958.75 12:26:05 INFO - PROCESS | 27350 | Values: 1205.2 1346.2 1419.7 1476.5 1731.4 6602.1 7503.8 7777.0 7786.6 7787.8 7511.4 7758.2 7771.5 7935.1 7935.9 1912.1 2159.7 2190.2 2226.0 2242.6 2226.2 2230.7 2232.4 2235.5 2237.1 2143.4 2169.7 2212.8 2213.0 2220.2 1850.3 1913.5 1916.7 2101.3 2201.0 2209.6 2230.7 2236.0 2237.1 2242.6 7822.9 7883.1 7883.6 7924.3 7976.7 4343.5 4421.3 4459.7 4465.5 4469.8 2848.3 2901.7 2963.8 2968.1 2970.5 905.9 925.5 933.0 953.5 956.3 10774.4 10931.0 10944.5 10984.9 11000.8 4116.7 4229.2 4345.0 4346.9 4350.7 9341.9 9396.9 9398.2 9405.9 9409.9 4686.0 4687.6 4688.5 4688.9 4694.1 3671.4 4030.3 4196.9 4220.1 4254.1 3659.6 3687.2 3757.1 3785.1 3819.0 1069.2 1152.2 1181.2 1188.3 1205.2 676.2 699.0 734.0 787.5 813.3 5232.5 5339.5 5369.8 5401.1 5438.0 870.8 876.6 884.0 894.7 913.7 3599.6 3662.6 3709.8 3754.2 3800.5 9726.7 9976.1 10029.3 10050.3 10052.8 8969.1 9950.2 9968.8 9989.8 9992.9 7128.0 7348.8 7503.3 7513.7 7515.5 5367.2 5502.3 5634.0 5667.5 5786.1 8728.2 8992.3 9061.1 9145.1 9233.1 9061.5 9259.7 9349.5 9392.2 9525.7 6774.2 6958.8 6985.2 7109.5 7146.6 7830.5 7844.4 7892.3 7896.2 7911.3 6890.0 6986.3 7061.5 7106.8 7128.3 6946.3 6975.1 7000.7 7071.4 7091.1 5787.1 5797.0 5821.0 5863.6 5867.7 4363.9 4388.5 4433.4 4442.6 4557.5 4473.9 4557.6 4562.4 4618.7 4659.7 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | [#4] prototype.html Cycles:180 Average:3983.79 Median:4331.87 stddev:1146.11 (26.5%) stddev-sans-first:1115.10 12:26:05 INFO - PROCESS | 27350 | Values: 279.7 291.2 293.7 296.3 296.9 4622.8 4707.9 4795.8 4836.9 4836.9 4584.6 4711.8 4721.3 4725.7 4740.4 3751.7 3758.2 3766.8 3774.2 3776.4 3587.4 3644.5 3763.8 3764.2 3788.2 3703.1 3746.5 3754.4 3756.4 3768.9 3751.1 3754.2 3759.7 3767.8 3778.2 3336.6 3339.8 3340.2 3348.6 3349.4 4567.2 4627.9 4649.3 4649.5 4680.6 4238.2 4255.0 4256.3 4272.4 4277.9 4966.3 4968.9 4977.6 4978.6 4985.6 3395.4 3568.7 3574.6 3638.4 3648.7 5181.4 5230.6 5247.6 5251.9 5263.8 3589.0 3610.5 3615.4 3630.5 3636.5 3653.6 3657.1 3663.2 3667.5 3673.1 3982.9 4226.7 4227.4 4231.1 4245.5 4700.2 4934.4 5024.8 5040.3 5042.3 1229.4 1259.3 1260.7 1266.4 1268.0 797.2 799.9 818.3 819.0 819.6 3962.0 3983.6 3986.6 3992.6 3997.0 4484.4 4508.5 4509.6 4544.2 4565.9 3901.5 3921.3 3982.2 4014.0 4018.1 2348.8 2453.5 2463.8 2465.4 2466.4 4326.9 4336.8 4345.2 4346.4 4356.9 4610.0 4620.8 4640.2 4663.8 4681.0 4058.4 4280.2 4300.7 4302.8 4303.1 4523.9 4546.5 4554.5 4556.0 4559.3 4446.2 4470.5 4475.8 4486.3 4492.0 4113.9 4258.9 4273.3 4299.4 4308.8 3313.3 3328.5 3334.8 3336.6 3338.4 4514.9 4523.3 4540.8 4548.8 4914.2 4755.7 4998.7 5002.4 5008.9 5015.9 4992.9 4995.4 5001.6 5002.9 5010.6 4810.3 4928.3 4943.3 4960.9 4961.7 4986.3 5008.5 5013.5 5020.6 5035.4 4903.3 4906.3 4945.8 4977.9 4978.5 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - PROCESS | 27350 | [#5] yui.html Cycles:180 Average:16741.85 Median:523.32 stddev:23296.01 (4451.6%) stddev-sans-first:23330.03 12:26:05 INFO - PROCESS | 27350 | Values: 650.3 668.3 682.5 684.3 747.4 13091.1 13974.4 13981.2 13991.9 14018.8 12106.0 13078.3 13081.5 13099.1 13106.1 617.4 632.8 779.1 786.6 794.3 796.0 801.5 803.5 804.3 805.7 842.0 848.7 850.4 850.4 850.7 651.1 796.5 827.5 843.5 844.1 611.2 614.3 614.3 614.8 614.9 13504.5 13505.5 13505.6 13518.4 13564.5 12463.8 13026.3 13043.3 13092.3 13314.8 11751.5 11940.8 12198.9 12320.1 12638.7 4019.6 4045.3 4095.5 4180.4 4188.1 61020.7 61230.8 61625.4 61937.0 62041.7 10483.8 10917.6 10957.7 10972.7 11015.3 18086.8 18118.5 18147.8 18155.6 18162.9 6718.5 6884.0 6922.7 7086.3 7318.6 3748.2 3752.6 3813.3 3815.2 3821.2 2755.6 2842.5 2843.2 2844.0 2844.3 509.0 514.1 518.2 525.8 535.3 538.7 549.8 549.8 554.7 567.4 7243.8 7472.9 7769.3 8003.2 8138.9 508.3 519.5 520.8 538.9 542.1 3579.3 3668.3 3727.4 3808.9 3855.3 76142.6 79654.2 80110.2 90165.0 91993.5 79878.2 80005.0 81145.6 81888.6 81942.0 44514.0 46663.8 72260.3 72701.3 75878.8 7294.5 7529.7 7564.2 7899.5 8039.5 31678.2 37118.4 41488.8 42603.8 45366.1 62511.7 62907.0 64563.7 66336.3 66444.7 9041.2 9147.1 9149.0 9209.5 9245.3 10393.3 10459.4 10546.0 10558.8 10615.5 7988.4 11079.3 11302.5 11650.8 11776.3 10922.6 11252.0 11256.1 11391.4 11475.2 11026.9 11047.0 11049.1 12311.8 12327.1 9079.3 9353.3 9544.7 9547.6 9556.1 9373.3 10060.7 10116.4 10219.8 10252.2 12:26:05 INFO - PROCESS | 27350 | -------- Summary: end -------- 12:26:05 INFO - PROCESS | 27350 | 12:26:05 INFO - TEST-INFO | 27350: exit 0 12:26:06 INFO - TEST-OK | dromaeo_css | took 1120015ms 12:26:06 INFO - TEST-START | kraken 12:26:06 INFO - operating with platform_type : linux_ 12:26:06 INFO - Initialising browser for kraken test... 12:26:06 INFO - TEST-INFO | started process 27544 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiFM7th/profile http://localhost:35743/getInfo.html) 12:26:10 INFO - PROCESS | 27544 | __metrics Screen width/height:1600/1200 12:26:10 INFO - PROCESS | 27544 | colorDepth:24 12:26:10 INFO - PROCESS | 27544 | Browser inner width/height: 1024/697 12:26:10 INFO - PROCESS | 27544 | __metrics 12:26:11 INFO - PROCESS | 27544 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:26:12 INFO - TEST-INFO | 27544: exit 0 12:26:12 INFO - Browser initialized. 12:26:12 INFO - Running cycle 1/1 for kraken test... 12:26:12 INFO - TEST-INFO | started process 27626 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiFM7th/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:26:24 INFO - PROCESS | 27626 | RSS: Main: 158642176 12:26:24 INFO - PROCESS | 27626 | 12:26:27 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35743/tests/kraken/driver.html?testName=audio-beat-detection) 12:26:28 INFO - PROCESS | 27626 | RSS: Main: 216465408 12:26:28 INFO - PROCESS | 27626 | 12:26:30 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35743/tests/kraken/driver.html?testName=audio-dft) 12:26:31 INFO - PROCESS | 27626 | RSS: Main: 203624448 12:26:31 INFO - PROCESS | 27626 | 12:26:34 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35743/tests/kraken/driver.html?testName=audio-fft) 12:26:35 INFO - PROCESS | 27626 | RSS: Main: 271126528 12:26:35 INFO - PROCESS | 27626 | 12:26:37 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35743/tests/kraken/driver.html?testName=audio-oscillator) 12:26:38 INFO - PROCESS | 27626 | RSS: Main: 182128640 12:26:38 INFO - PROCESS | 27626 | 12:26:40 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35743/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:26:40 INFO - PROCESS | 27626 | RSS: Main: 185843712 12:26:40 INFO - PROCESS | 27626 | 12:26:44 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35743/tests/kraken/driver.html?testName=imaging-darkroom) 12:26:44 INFO - PROCESS | 27626 | RSS: Main: 221638656 12:26:44 INFO - PROCESS | 27626 | 12:26:48 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35743/tests/kraken/driver.html?testName=imaging-desaturate) 12:26:48 INFO - PROCESS | 27626 | RSS: Main: 217559040 12:26:48 INFO - PROCESS | 27626 | 12:26:51 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35743/tests/kraken/driver.html?testName=json-parse-financial) 12:26:52 INFO - PROCESS | 27626 | RSS: Main: 220553216 12:26:52 INFO - PROCESS | 27626 | 12:26:54 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35743/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:26:54 INFO - PROCESS | 27626 | RSS: Main: 207556608 12:26:54 INFO - PROCESS | 27626 | 12:26:56 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:26:57 INFO - PROCESS | 27626 | RSS: Main: 211550208 12:26:57 INFO - PROCESS | 27626 | 12:26:59 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:27:00 INFO - PROCESS | 27626 | RSS: Main: 212611072 12:27:00 INFO - PROCESS | 27626 | 12:27:02 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:27:03 INFO - PROCESS | 27626 | RSS: Main: 226746368 12:27:03 INFO - PROCESS | 27626 | 12:27:06 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:27:06 INFO - PROCESS | 27626 | RSS: Main: 226127872 12:27:06 INFO - PROCESS | 27626 | 12:27:08 INFO - PROCESS | 27626 | Cycle 1(1): loaded http://localhost:35743/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35743/tests/kraken/driver.html?testName=ai-astar) 12:27:09 INFO - PROCESS | 27626 | RSS: Main: 268218368 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | __start_tp_report 12:27:09 INFO - PROCESS | 27626 | _x_x_mozilla_page_load 12:27:09 INFO - PROCESS | 27626 | _x_x_mozilla_page_load_details 12:27:09 INFO - PROCESS | 27626 | |i|pagename|runs| 12:27:09 INFO - PROCESS | 27626 | |0;ai-astar;105;114;114;112;115;112;113;113;113;114 12:27:09 INFO - PROCESS | 27626 | |1;audio-beat-detection;158;124;125;175;127;129;154;128;169;121 12:27:09 INFO - PROCESS | 27626 | |2;audio-dft;159;162;163;161;160;161;160;161;161;161 12:27:09 INFO - PROCESS | 27626 | |3;audio-fft;77;75;76;120;76;76;76;82;103;75 12:27:09 INFO - PROCESS | 27626 | |4;audio-oscillator;84;94;117;83;85;85;120;85;85;126 12:27:09 INFO - PROCESS | 27626 | |5;imaging-gaussian-blur;117;118;117;116;117;117;117;117;116;117 12:27:09 INFO - PROCESS | 27626 | |6;imaging-darkroom;147;146;147;147;147;146;146;147;145;146 12:27:09 INFO - PROCESS | 27626 | |7;imaging-desaturate;95;95;95;94;95;95;95;94;95;95 12:27:09 INFO - PROCESS | 27626 | |8;json-parse-financial;63;63;62;63;63;62;63;62;63;62 12:27:09 INFO - PROCESS | 27626 | |9;json-stringify-tinderbox;65;64;65;65;64;63;64;64;65;64 12:27:09 INFO - PROCESS | 27626 | |10;stanford-crypto-aes;79;79;78;87;78;79;82;79;84;79 12:27:09 INFO - PROCESS | 27626 | |11;stanford-crypto-ccm;139;131;131;130;128;131;133;131;203;152 12:27:09 INFO - PROCESS | 27626 | |12;stanford-crypto-pbkdf2;169;171;167;174;167;168;170;166;168;169 12:27:09 INFO - PROCESS | 27626 | |13;stanford-crypto-sha256-iterative;68;67;68;67;68;67;68;67;67;68 12:27:09 INFO - PROCESS | 27626 | __end_tp_report 12:27:09 INFO - PROCESS | 27626 | __start_cc_report 12:27:09 INFO - PROCESS | 27626 | _x_x_mozilla_cycle_collect,1921 12:27:09 INFO - PROCESS | 27626 | __end_cc_report 12:27:09 INFO - PROCESS | 27626 | __startTimestamp1467314829007__endTimestamp 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | ------- Summary: start ------- 12:27:09 INFO - PROCESS | 27626 | Number of tests: 14 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:2.80 (2.5%) stddev-sans-first:1.00 12:27:09 INFO - PROCESS | 27626 | Values: 105.0 114.0 114.0 112.0 115.0 112.0 113.0 113.0 113.0 114.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:141.50 stddev:20.69 (14.6%) stddev-sans-first:21.01 12:27:09 INFO - PROCESS | 27626 | Values: 158.0 124.0 125.0 175.0 127.0 129.0 154.0 128.0 169.0 121.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:0.93 12:27:09 INFO - PROCESS | 27626 | Values: 159.0 162.0 163.0 161.0 160.0 161.0 160.0 161.0 161.0 161.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:15.37 (20.2%) stddev-sans-first:16.12 12:27:09 INFO - PROCESS | 27626 | Values: 77.0 75.0 76.0 120.0 76.0 76.0 76.0 82.0 103.0 75.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:85.00 stddev:17.37 (20.4%) stddev-sans-first:17.84 12:27:09 INFO - PROCESS | 27626 | Values: 84.0 94.0 117.0 83.0 85.0 85.0 120.0 85.0 85.0 126.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 12:27:09 INFO - PROCESS | 27626 | Values: 117.0 118.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 12:27:09 INFO - PROCESS | 27626 | Values: 147.0 146.0 147.0 147.0 147.0 146.0 146.0 147.0 145.0 146.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 12:27:09 INFO - PROCESS | 27626 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:63.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 12:27:09 INFO - PROCESS | 27626 | Values: 63.0 63.0 62.0 63.0 63.0 62.0 63.0 62.0 63.0 62.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:27:09 INFO - PROCESS | 27626 | Values: 65.0 64.0 65.0 65.0 64.0 63.0 64.0 64.0 65.0 64.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:79.00 stddev:2.99 (3.8%) stddev-sans-first:3.13 12:27:09 INFO - PROCESS | 27626 | Values: 79.0 79.0 78.0 87.0 78.0 79.0 82.0 79.0 84.0 79.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#11] stanford-crypto-ccm Cycles:10 Average:140.90 Median:132.00 stddev:22.91 (17.4%) stddev-sans-first:24.29 12:27:09 INFO - PROCESS | 27626 | Values: 139.0 131.0 131.0 130.0 128.0 131.0 133.0 131.0 203.0 152.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.00 stddev:2.33 (1.4%) stddev-sans-first:2.47 12:27:09 INFO - PROCESS | 27626 | Values: 169.0 171.0 167.0 174.0 167.0 168.0 170.0 166.0 168.0 169.0 12:27:09 INFO - PROCESS | 27626 | 12:27:09 INFO - PROCESS | 27626 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 12:27:09 INFO - PROCESS | 27626 | Values: 68.0 67.0 68.0 67.0 68.0 67.0 68.0 67.0 67.0 68.0 12:27:09 INFO - PROCESS | 27626 | -------- Summary: end -------- 12:27:09 INFO - PROCESS | 27626 | 12:27:10 INFO - TEST-INFO | 27626: exit 0 12:27:10 INFO - TEST-OK | kraken | took 64094ms 12:27:10 INFO - SUITE-END | took 1184s 12:27:10 INFO - Completed test suite (00:19:44) 12:27:10 INFO - javascript benchmark 12:27:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2621.580547112462, 2628.8817003234853, 2668.132856821533, 2678.357194273375, 2680.222735406732, 4620.537946205378, 4697.624190064791, 4721.78751956162, 4766.237402015674, 4819.951800481998, 4744.715317080969, 4751.12104260712, 4784.784684689189, 4788.784504697288, 4792.04159168166, 4343.761093139876, 4350.956490435103, 4408.911821763579, 4439.356293337464, 4541.159885421192, 4439.223135951195, 4475.7762111894435, 4487.483939346968, 4488.394066800973, 4518.593326600605, 4279.935800962988, 4321.956780432219, 4378.277584198603, 4413.492448368446, 4419.845305414295, 4387.780610969438, 4390.078083602415, 4393.143337049277, 4444.933326000112, 4449.26587113126, 4399.7360158390275, 4417.2269852775635, 4421.182081314967, 4452.510223875371, 4463.553644635543, 4652.581267685924, 4782.282657601353, 4788.784504697297, 4841.685290456109, 4850.029994001214, 5189.221616757479, 5202.193659982709, 5204.609654275944, 5205.297284866532, 5205.505476979681, 5145.922811157836, 5180.818671346522, 5217.139172036609, 5231.92745487178, 5251.711155886424, 4680.3681502997, 4790.640701947368, 4797.544233297831, 4833.589144922721, 4886.902261954741, 5324.307839980777, 5346.304980352529, 5348.331458567679, 5349.1976203569, 5351.919721204185, 4839.685420447659, 5058.519440653132, 5108.259302401131, 5112.642115051911, 5120.667156634807, 5154.639175257696, 5205.06308864408, 5206.427292997767, 5213.895722085537, 5222.608304377187, 5165.638405311592, 5175.611829112831, 5175.715335656575, 5178.766955487012, 5229.111051121318, 4936.48166942473, 5050.671706339076, 5147.922781158286, 5160.819371322061, 5168.9741551292, 4371.803268852909, 4377.715448495837, 4391.912161756747, 4432.3794668746405, 4440.7335559866515, 3800.923981520354, 3808.3906574947873, 3824.2351529694174, 3853.0174805424435, 3858.594847541024, 4285.892852678646, 4328.675349348811, 4382.890427739332, 4422.115576884636, 4427.490838553543, 5174.14626586609, 5174.456682048406, 5194.22086687, 5204.297419848348, 5251.474852514718, 4433.357163211316, 4437.378766972626, 4440.666949978765, 4445.844395446144, 4474.686771925999, 4534.59188673021, 4571.748553829571, 4666.883327916829, 4670.299455081765, 4713.74074425903, 4854.8786280342565, 4893.266010098514, 4921.9015619688125, 4922.064807686528, 4946.554810067111, 5243.344581927259, 5244.921326180034, 5254.500822421864, 5258.7107709074835, 5304.920426193533, 4711.952880471152, 4836.95163048365, 4895.534924182197, 4900.264960255848, 4908.165611845923, 5018.971110922325, 5051.4443411225475, 5057.31726216956, 5062.392512898476, 5068.594512438923, 5127.512886275797, 5129.948700512947, 5131.153359695609, 5133.4096578893905, 5192.532672059533, 4840.177169881199, 4990.476000019946, 5002.774875130555, 5006.874828129326, 5016.021875734197, 5040.49595040493, 5059.696418214919, 5061.924071139009, 5081.136206844919, 5115.565176959852, 5008.198688209869, 5021.322121513549, 5021.974890125526, 5071.492850714899, 5077.8730531737, 4883.560479556715, 4936.160852655129, 4963.057019166346, 5109.029284435946, 5155.304033955368, 4860.659753817199, 4884.682495637772, 4887.731174785279, 4930.408350997904, 4942.777575009205], "value": 4730.020776117483, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.0319879574748, 584.5119325363321, 590.052964991189, 598.5152026858242, 602.3404372212428, 27185.0, 39791.0, 40699.59300406995, 40894.0, 40924.79537602308, 40039.79980100103, 40430.59569404305, 40441.19117617646, 40544.39183412244, 40575.797121014424, 7568.508046976929, 7790.727324543642, 7791.4935529190425, 7939.801504962379, 8098.190180981884, 7672.769816905474, 10048.296619236657, 10308.587656493732, 10312.896871031306, 10656.520456579434, 10320.845187322197, 10393.272470927039, 10393.37639741618, 10407.479626018707, 10407.53166107527, 7648.38812894967, 10165.542550585167, 10310.536025878815, 10367.37795732251, 10369.792604147913, 9912.851307230398, 10196.286259961806, 10205.48972551377, 10206.0, 10209.336393134423, 38489.0, 38781.224375512335, 38858.61141388578, 38871.8056409719, 38901.0, 3191.3138675184946, 3193.409219294438, 3398.609159946613, 3708.740388172802, 3802.8478860845535, 27557.586636200474, 29304.0, 29456.8527157365, 29572.70427295721, 30423.847880760455, 3276.6723327667282, 3357.61387440445, 3367.8652853885815, 3372.932541349184, 3392.1858753899132, 273674.0, 274331.0, 275260.0, 275501.0, 276154.6192269046, 27924.860375698398, 47749.522504774504, 48084.5191548087, 48157.0, 48253.51746482561, 33813.830930845186, 33901.49147762785, 33951.83024084913, 33993.0, 34004.97985060452, 13113.475460981645, 13243.470261189637, 13243.536476223286, 13292.800607990888, 13307.800382994263, 9713.514324283899, 10664.520096595672, 10669.466526673636, 10773.83839242412, 10972.670819875417, 7787.260210280015, 7857.489263197874, 7869.331106855867, 7911.129775724666, 7934.127246002935, 3090.629124505074, 3091.381723655278, 3099.7136188481527, 3115.6572776994512, 3125.24994001438, 873.323174539724, 882.571952602987, 903.4172958441768, 918.1598837064012, 1019.6533178719271, 26612.201633951012, 26873.193804185907, 27622.0, 27656.446871062468, 27712.168634940983, 963.1716723617683, 1005.8038682456851, 1034.3018462537757, 1062.6227689170232, 1075.2365820267698, 14813.185274809988, 14837.777433338508, 14863.405463781328, 15138.0, 15204.619884502754, 401933.0, 409605.0, 414523.0, 416983.0, 577107.0, 48831.26753098706, 49051.26423103656, 49259.75370123198, 49338.50661493411, 49413.0, 1096.1066730614523, 1203.4524291447547, 1229.170310040737, 1232.8212409200617, 1242.0250103668577, 24802.875985620678, 28470.43059138847, 28770.28074298159, 28836.0, 28863.0, 40591.1881762369, 40940.0, 41234.175316494104, 41321.586784131774, 41341.37987930123, 308547.0, 310959.0, 311171.0, 311607.0, 312073.0, 22513.549729005656, 24458.75541244636, 25495.0, 25530.23409297724, 25564.360890977874, 40410.797946011255, 40813.91860813895, 41356.1728765417, 41544.376834346906, 42338.788306058275, 35477.0, 35505.0, 36149.63850361568, 36461.45307820332, 36607.45088823616, 35882.4617630741, 35949.46075808813, 36256.0, 36496.81751591331, 36678.8166059168, 34399.0, 34952.300953980266, 34969.0, 35325.293494130485, 35556.0, 30736.538951916187, 32099.197520062186, 32662.83668581642, 32704.83647581747, 32778.508322374706, 24539.509209815347, 24986.250412487654, 25120.246392608253, 25316.493670126863, 25459.872700636377], "value": 16952.658016913952, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.98285085745704, 371.06676708079186, 372.7223218702069, 376.7287552961868, 387.50205985308867, 12974.61076167714, 17186.5703357416, 17945.82054179458, 18342.81657183428, 18346.26614935401, 16768.58078548034, 17518.649627007453, 17969.91015044926, 17970.820291797078, 17982.730259046093, 4876.487968763291, 5312.256284120207, 5315.202719592073, 5315.734213289339, 5317.468253174671, 3663.3955397359405, 5181.35233095863, 5837.708114594275, 6143.754249830002, 6157.568970172068, 6173.536984726141, 6187.133801267826, 6201.875962480748, 6211.40991605799, 6215.627062376249, 5034.295198672189, 5260.92108618371, 5264.447233040514, 5336.9733151334385, 5351.785928562853, 3361.3109312591105, 4980.551750342486, 5153.3815942087185, 5303.893922121574, 5330.5735541157, 12511.436985335591, 12655.936720316431, 12766.744665106646, 15305.0, 21384.14463421477, 1793.6323053773945, 1916.0611300462813, 1961.9509512262166, 2018.7173795668618, 2472.208893154191, 9466.715998520056, 9744.610215591369, 9764.170045546138, 11704.005159561448, 12624.68438289041, 1747.860171186302, 1810.6650269700147, 1853.8146185381488, 1861.1159699142934, 1866.2348437140843, 65588.3441165587, 65955.67022164907, 68521.31478685199, 69268.0, 74536.25463745401, 8786.86819697705, 9553.42679439222, 10049.949750251202, 10113.027959547964, 10182.643607473703, 12556.81164782529, 13751.587452376445, 13807.378667959965, 14236.28818559068, 14247.928760356339, 6803.897941530881, 6959.668511048754, 6997.685104170325, 7024.789256322319, 7096.574205547684, 6527.0, 6534.934650653528, 6566.540342176002, 6667.566608170453, 6794.694238759268, 4440.511543730187, 4441.9333709993725, 4445.155420470101, 4469.41897553316, 4510.639148868083, 1794.2052558837963, 1845.3264558436074, 1849.6115815678588, 1867.8599105067174, 1901.7970285668828, 781.8944442500303, 870.8171284030298, 911.2937473458123, 921.4977837078808, 932.4172392254841, 17039.880890137243, 17177.742333865, 17756.0, 17840.821591783915, 17884.55288617795, 868.6525389844113, 873.1966590736538, 876.7150676030316, 887.5917078143997, 887.7603047177226, 9482.288828337902, 9711.125998660154, 9751.512424378752, 9973.850392244121, 10181.54183061764, 367509.0, 367658.0, 382659.0, 384657.0, 385071.0, 51150.0, 51418.228726569134, 66489.0, 80945.59527202326, 81872.59063704644, 871.0723079919992, 885.5218182181678, 889.5374405309203, 899.4378513429107, 901.1799262670938, 15489.5353139406, 15572.454964076656, 15722.21388930526, 15934.840651593335, 16238.269277882287, 36761.448578270814, 38090.8095459532, 39102.78806512514, 39115.41326880156, 39991.000224994605, 50159.74920125376, 52506.0, 53092.20361694655, 53250.0, 53991.0, 7400.333969942731, 7636.198199189005, 7667.769966901002, 7692.7692169235015, 7902.525848449111, 9843.507824608598, 11600.0, 11709.180357374902, 11788.115891308358, 12059.698507537381, 10001.399916005063, 10042.397456152654, 10087.69736907894, 10113.646022389476, 10238.948805255926, 10134.594616215269, 10340.27618066728, 10359.0, 10445.738856528647, 10460.21548383859, 9018.954905225432, 9759.707208783464, 10064.446455444728, 10199.28604997643, 10533.4733263335, 7358.411327093927, 7603.7338693145475, 7741.95483609728, 7767.300942914939, 7897.93377894, 8743.868841967504, 9989.750256243651, 10130.290879638354, 10230.846537301797, 10330.741731456474], "value": 8163.053324408072, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1205.240698360033, 1346.165377465971, 1419.74444599972, 1476.4537121265134, 1731.4459373000645, 6602.1417215762, 7503.774886753399, 7777.0, 7786.571738554391, 7787.766367008991, 7511.43664225184, 7758.185390533998, 7771.455998080137, 7935.087464941534, 7935.880961785578, 1912.0726447672866, 2159.730033745782, 2190.2005767894725, 2226.0428015953134, 2242.551489702058, 2226.2430773536994, 2230.698855654482, 2232.386093824195, 2235.508188198594, 2237.1163390460756, 2143.3891340967825, 2169.6745488176743, 2212.811910987568, 2212.9557408851815, 2220.245116660335, 1850.2598960415808, 1913.4833594929291, 1916.6741653918866, 2101.2540548105353, 2200.9559808803815, 2209.569134018867, 2230.6877037214804, 2236.0, 2237.071615279657, 2242.5627002734473, 7822.921770782333, 7883.054033515958, 7883.645235964395, 7924.286814186693, 7976.6809327626825, 4343.543927887589, 4421.292593185107, 4459.710118842265, 4465.531119232466, 4469.843555475575, 2848.2879280179955, 2901.738843504095, 2963.777716671258, 2968.0650595062484, 2970.4504666636526, 905.9320550958704, 925.4771054354268, 933.0389698610442, 953.5232383808096, 956.3305686019744, 10774.35353878762, 10931.0, 10944.507497162647, 10984.890151098467, 11000.834987475195, 4116.670666346685, 4229.175310814423, 4344.978275108668, 4346.869593912188, 4350.71720338177, 9341.859872101924, 9396.90603093974, 9398.154166124945, 9405.905940940504, 9409.905900940903, 4685.9765701171955, 4687.648426368036, 4688.531146885284, 4688.906221875612, 4694.0611877624615, 3671.4125739881492, 4030.314846476106, 4196.91606167881, 4220.134872351161, 4254.106637606075, 3659.579148397915, 3687.2441149564274, 3757.1358587525024, 3785.09157802131, 3818.961810381916, 1069.2301542889263, 1152.176194021262, 1181.2381014245764, 1188.292965685433, 1205.1744554979866, 676.2459857259121, 698.9755358562426, 733.9706411743578, 787.5392895156398, 813.32494029955, 5232.476752324813, 5339.492748188916, 5369.785208591691, 5401.054815407288, 5438.048341540215, 870.7518357268148, 876.5748611923212, 883.9878339301464, 894.6913314906346, 913.7167478081816, 3599.5500562429697, 3662.61542538035, 3709.8330575124182, 3754.2491501699224, 3800.467934489174, 9726.70819875405, 9976.102150806462, 10029.297949143636, 10050.296479246528, 10052.798944021079, 8969.14793094655, 9950.154236889946, 9968.80062398748, 9989.750256243507, 9992.850107248398, 7128.002079708845, 7348.77953661391, 7503.324700776956, 7513.699452021858, 7515.549067055994, 5367.221752845893, 5502.257195278747, 5634.0, 5667.489925906688, 5786.074228123648, 8728.170823771732, 8992.28061755071, 9061.093890610886, 9145.085491450802, 9233.122853328923, 9061.501617411106, 9259.67591134334, 9349.532523373668, 9392.248620110511, 9525.666601668909, 6774.153230846145, 6958.79123626292, 6985.231624521197, 7109.502334836719, 7146.571205727673, 7830.530168189927, 7844.411669124953, 7892.329152022143, 7896.170902055431, 7911.327537159406, 6890.0, 6986.301369862973, 7061.505694601328, 7106.786796396116, 7128.28717128283, 6946.270641582561, 6975.093237879043, 7000.684969176501, 7071.434285257064, 7091.078159839188, 5787.102999035156, 5797.043487824461, 5821.0, 5863.589548731547, 5867.706614669164, 4363.912721745611, 4388.4733831940375, 4433.401490798701, 4442.622377097983, 4557.521460246759, 4473.865784026484, 4557.635389168792, 4562.406887104655, 4618.676692631618, 4659.650526210616], "value": 4161.028014069463, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.7244713956753, 291.1861347533644, 293.73710529076476, 296.2682175027681, 296.9020223326303, 4622.791974361154, 4707.858764237069, 4795.808167673294, 4836.927446088307, 4836.927446088307, 4584.633229341653, 4711.8455978285365, 4721.268203428465, 4725.6928299660585, 4740.407449068866, 3751.6623503884643, 3758.2483503299313, 3766.811659417032, 3774.1508160663907, 3776.3957766757326, 3587.408077667195, 3644.5079914211506, 3763.8306276217636, 3764.171882185915, 3788.2234142000825, 3703.1112532992024, 3746.5316835395574, 3754.4180651998986, 3756.417755247941, 3768.9057773555605, 3751.118487155516, 3754.1740817020213, 3759.718021148411, 3767.8492860285555, 3778.2254637799183, 3336.582927134108, 3339.8497067632015, 3340.16495876031, 3348.648391918862, 3349.363620912043, 4567.246404343313, 4627.907441851178, 4649.279361698942, 4649.465311489188, 4680.602148817355, 4238.237117318881, 4255.0, 4256.34026725858, 4272.423222864904, 4277.850275240383, 4966.329545511346, 4968.857162852564, 4977.552020318189, 4978.576820970222, 4985.626078044161, 3395.422778127724, 3568.678818906311, 3574.6067932527403, 3638.381475149231, 3648.7445878788494, 5181.3782346118705, 5230.581553475751, 5247.606429517763, 5251.947480525184, 5263.815766448156, 3588.982055089708, 3610.548681414823, 3615.439606860941, 3630.546181727284, 3636.5090712753968, 3653.598104208535, 3657.085728567858, 3663.157473781045, 3667.504886840296, 3673.1000904777966, 3982.9402558961638, 4226.725262857904, 4227.35133298014, 4231.111466591989, 4245.532991370947, 4700.177468942921, 4934.383202099738, 5024.84925452237, 5040.319556859777, 5042.344495215599, 1229.440604524939, 1259.3325537465157, 1260.691130672991, 1266.360487953596, 1268.0, 797.214743477674, 799.8760192170223, 818.333058557274, 819.0376307838267, 819.6311659753015, 3961.9801900990888, 3983.5817239190046, 3986.60133986599, 3992.5608183099835, 3996.980015099906, 4484.394606728115, 4508.526604706524, 4509.571590698878, 4544.2047641662575, 4565.863024109282, 3901.51231096113, 3921.313770090223, 3982.2433737589763, 4013.979930100331, 4018.0668112277617, 2348.8120950323932, 2453.5338285725657, 2463.7782599565767, 2465.395977985405, 2466.432720474265, 4326.913461730748, 4336.826526938887, 4345.196138714348, 4346.391835020999, 4356.934645980313, 4609.976950115295, 4620.8151673933335, 4640.23436133041, 4663.790129444184, 4680.95319046812, 4058.411530328086, 4280.186764514732, 4300.677449191386, 4302.806373713191, 4303.096349766584, 4523.864284071483, 4546.477155127202, 4554.453465584019, 4556.0, 4559.270516717309, 4446.221911165469, 4470.530594287683, 4475.753833539186, 4486.304622783474, 4491.977540112279, 4113.876583702494, 4258.893527661833, 4273.252180868273, 4299.398084268142, 4308.806103725403, 3313.254517733587, 3328.53400523922, 3334.7999120052873, 3336.6329703733054, 3338.3657105149946, 4514.90970180588, 4523.32150177476, 4540.818367265272, 4548.840790572314, 4914.164592019294, 4755.714657120584, 4998.6500944933505, 5002.399712034579, 5008.9248661269385, 5015.899682006412, 4992.850214493571, 4995.350604421402, 5001.64988450805, 5002.949970500248, 5010.624203184703, 4810.302506136521, 4928.2853986171085, 4943.332650092191, 4960.851174464772, 4961.70229786214, 4986.252062190701, 5008.499150084962, 5013.498650134958, 5020.648554601143, 5035.395752509722, 4903.289023091703, 4906.2640603909695, 4945.777440015136, 4977.925331120108, 4978.5270399312], "value": 3621.7549213232555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.3236633900742, 668.3216535216756, 682.5120039171991, 684.3225207045027, 747.439420434674, 13091.14907531011, 13974.3711532981, 13981.231032293219, 13991.93004034981, 14018.789718154236, 12106.0, 13078.280694561796, 13081.476740930375, 13099.14855534392, 13106.082574219809, 617.3918690090258, 632.7658766256492, 779.0651218537763, 786.6224212378062, 794.2653045932518, 795.9562224077673, 801.5230937592153, 803.5018288661009, 804.3082948664144, 805.7059173401695, 842.0442797424905, 848.6859861851124, 850.4344610833788, 850.4472093139447, 850.706506255341, 651.072222083529, 796.5061661769682, 827.5283088639418, 843.5023336231623, 844.0799528513919, 611.1901730207449, 614.2751553167261, 614.3181069013392, 614.7725341623583, 614.8678034222663, 13504.459821607124, 13505.527306544223, 13505.594832155051, 13518.391672374766, 13564.45742170312, 12463.813042804366, 13026.348682565835, 13043.347832608426, 13092.279924604143, 13314.800277995837, 11751.529938802438, 11940.820887686692, 12198.878011219951, 12320.137590368679, 12638.74722505554, 4019.6382325590544, 4045.2920738870876, 4095.467589213413, 4180.393842892763, 4188.057687020396, 61020.69489652523, 61230.7753844925, 61625.383746162865, 61937.0, 62041.6897915512, 10483.842742358871, 10917.617883374203, 10957.726056848642, 10972.725681858017, 11015.28400653971, 18086.819131808777, 18118.45644630663, 18147.818521814614, 18155.636887262182, 18162.909185453987, 6718.49611279156, 6884.0, 6922.651189924174, 7086.326798954091, 7318.560886346836, 3748.1754014117396, 3752.6247375262255, 3813.3326667833016, 3815.236952609489, 3821.178446633987, 2755.641766570333, 2842.459932612838, 2843.232327271625, 2843.957340639892, 2844.3458004659046, 508.98473045807947, 514.1311184098861, 518.2356024863371, 525.821220784935, 535.2640119835227, 538.7171734839241, 549.8130635583921, 549.84879158231, 554.7337278106531, 567.4212303450457, 7243.782686519413, 7472.85054298911, 7769.339606133542, 8003.199680032067, 8138.9186108138165, 508.32000239209765, 519.4545726986725, 520.8177138001669, 538.9326334208224, 542.1352942057412, 3579.302036102987, 3668.284684486553, 3727.3849814780256, 3808.866689665904, 3855.325318069327, 76142.61928690321, 79654.20345796584, 80110.19889801144, 90165.0, 91993.54003230075, 79878.20121798824, 80005.0, 81145.59427202826, 81888.59055704683, 81942.0, 44514.0, 46663.76668116706, 72260.27739722641, 72701.27298727051, 75878.766140602, 7294.489385743159, 7529.661165247468, 7564.205758395289, 7899.526028438312, 8039.5176289422825, 31678.208044799063, 37118.44322335113, 41488.79255603703, 42603.786981066136, 45366.09267814559, 62511.68744156432, 62907.0, 64563.67718161567, 66336.33663663495, 66444.66777666274, 9041.231495322709, 9147.0852914708, 9148.954255228682, 9209.539523023956, 9245.260379169786, 10393.272470926962, 10459.424731639832, 10546.0, 10558.8416173759, 10615.522301496298, 7988.400869934894, 11079.279846809932, 11302.491387887729, 11650.825237621611, 11776.349331707199, 10922.56309747633, 11252.043576295779, 11256.099512038854, 11391.430428478709, 11475.196736228718, 11026.944865275622, 11047.0, 11049.116070714163, 12311.815322769986, 12327.075469340014, 9079.319051071065, 9353.298502612195, 9544.665936692183, 9547.618095276111, 9556.092171243721, 9373.25013998892, 10060.69817905464, 10116.393016419039, 10219.846702299323, 10252.231082668979], "value": 6404.286765369822, "unit": "score"}], "name": "dromaeo_css", "value": 6311.011378342683, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 114.0, 112.0, 115.0, 112.0, 113.0, 113.0, 113.0, 114.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 125.0, 175.0, 127.0, 129.0, 154.0, 128.0, 169.0, 121.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 163.0, 161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 120.0, 76.0, 76.0, 76.0, 82.0, 103.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 94.0, 117.0, 83.0, 85.0, 85.0, 120.0, 85.0, 85.0, 126.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 146.4, "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, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0, 62.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 64.0, 63.0, 64.0, 64.0, 65.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 87.0, 78.0, 79.0, 82.0, 79.0, 84.0, 79.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 131.0, 131.0, 130.0, 128.0, 131.0, 133.0, 131.0, 203.0, 152.0], "value": 140.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 167.0, 174.0, 167.0, 168.0, 170.0, 166.0, 168.0, 169.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1537.1000000000001, "alertThreshold": 2.0}]} 12:27:10 INFO - Return code: 0 12:27:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:27:10 INFO - # TBPL SUCCESS # 12:27:10 INFO - Running post-action listener: _resource_record_post_action 12:27:10 INFO - ##### 12:27:10 INFO - ##### Finished run-tests step (success) 12:27:10 INFO - ##### 12:27:10 INFO - Running post-run listener: _resource_record_post_run 12:27:10 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 559603712; Read time: 20; Write time: 487772 12:27:10 INFO - TinderboxPrint: CPU usage
13.0% 12:27:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 12:27:10 INFO - TinderboxPrint: I/O write bytes / time
559,603,712 / 487,772 12:27:10 INFO - TinderboxPrint: CPU idle
8,333.6 (86.9%) 12:27:10 INFO - TinderboxPrint: CPU user
1,206.7 (12.6%) 12:27:10 INFO - TinderboxPrint: Swap in / out
0 / 0 12:27:10 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 259465216; Read time: 0; Write time: 269804 12:27:10 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 299601920; Read time: 0; Write time: 217968 12:27:10 INFO - Running post-run listener: _upload_blobber_files 12:27:10 INFO - Blob upload gear active. 12:27:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:27:10 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 12:27:10 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'] 12:27:10 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 12:27:11 INFO - (blobuploader) - INFO - Open directory for files ... 12:27:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:27:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:27:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:12 INFO - (blobuploader) - INFO - Done attempting. 12:27:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:27:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:27:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:16 INFO - (blobuploader) - INFO - Done attempting. 12:27:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:27:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:27:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:16 INFO - (blobuploader) - INFO - Done attempting. 12:27:16 INFO - (blobuploader) - INFO - Iteration through files over. 12:27:16 INFO - Return code: 0 12:27:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:27:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:27:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dfe3576aa034c5ccd98b5e7b8f745607f581456e05461f3a8c6f599ea87de0c655ccd7e0eb7ce528ce42e02b433d1c662a23d1417ad779046e54dfb32f31b44c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4b32891a231f3a9b38814d5567c0114e33e1d4d39d43f1be8bd9b9eb6df0e2aaec403f6f4459b32aeacaf917ce267c28da4752c2c972e6aa293da5f57d1605bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d84b7154ec0424516268ff7f7d9d274878b35c65d9ebcfcdfe56796cfb8a0ced689657a9edfb48276f4e6f10dd59a8e74acf760b3016f0aa8946d4f675565f04"} 12:27:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:27:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:27:16 INFO - Contents: 12:27:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dfe3576aa034c5ccd98b5e7b8f745607f581456e05461f3a8c6f599ea87de0c655ccd7e0eb7ce528ce42e02b433d1c662a23d1417ad779046e54dfb32f31b44c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4b32891a231f3a9b38814d5567c0114e33e1d4d39d43f1be8bd9b9eb6df0e2aaec403f6f4459b32aeacaf917ce267c28da4752c2c972e6aa293da5f57d1605bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d84b7154ec0424516268ff7f7d9d274878b35c65d9ebcfcdfe56796cfb8a0ced689657a9edfb48276f4e6f10dd59a8e74acf760b3016f0aa8946d4f675565f04"} 12:27:16 INFO - Running post-run listener: copy_logs_to_upload_dir 12:27:16 INFO - Copying logs to upload dir... 12:27:16 INFO - mkdir: /builds/slave/test/build/upload/logs 12:27:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.644862 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-06-30 12:27:16.755046) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 12:27:16.758424) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132 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/dfe3576aa034c5ccd98b5e7b8f745607f581456e05461f3a8c6f599ea87de0c655ccd7e0eb7ce528ce42e02b433d1c662a23d1417ad779046e54dfb32f31b44c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4b32891a231f3a9b38814d5567c0114e33e1d4d39d43f1be8bd9b9eb6df0e2aaec403f6f4459b32aeacaf917ce267c28da4752c2c972e6aa293da5f57d1605bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d84b7154ec0424516268ff7f7d9d274878b35c65d9ebcfcdfe56796cfb8a0ced689657a9edfb48276f4e6f10dd59a8e74acf760b3016f0aa8946d4f675565f04"} build_url:https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/YeVDvWa4TE2d3wUmMaJQsw/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/dfe3576aa034c5ccd98b5e7b8f745607f581456e05461f3a8c6f599ea87de0c655ccd7e0eb7ce528ce42e02b433d1c662a23d1417ad779046e54dfb32f31b44c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/4b32891a231f3a9b38814d5567c0114e33e1d4d39d43f1be8bd9b9eb6df0e2aaec403f6f4459b32aeacaf917ce267c28da4752c2c972e6aa293da5f57d1605bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d84b7154ec0424516268ff7f7d9d274878b35c65d9ebcfcdfe56796cfb8a0ced689657a9edfb48276f4e6f10dd59a8e74acf760b3016f0aa8946d4f675565f04"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 12:27:16.783190) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 12:27:16.783472) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1467308739.890791-1234788132 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 12:27:16.868701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 12:27:16.869022) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 12:27:16.869324) ========= ========= Total master_lag: 0.18 =========