builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1471387517.9 results: success (0) buildid: 20160816135115 builduid: c5d3de27e8b64ac8aeef51fcb85e32ba revision: 34909044f6148f65dbc3d0fbee5edf67d54c7f0f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:17.904279) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:17.904746) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:17.905034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:18.215634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:18.216003) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 15:45:18-- 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% 38.0M=0s 2016-08-16 15:45:18 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.152863 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:18.382928) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:18.383269) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016636 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:18.436183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 15:45:18.436544) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 34909044f6148f65dbc3d0fbee5edf67d54c7f0f --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 34909044f6148f65dbc3d0fbee5edf67d54c7f0f --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=9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 15:45:18,522 truncating revision to first 12 chars 2016-08-16 15:45:18,522 Setting DEBUG logging. 2016-08-16 15:45:18,522 attempt 1/10 2016-08-16 15:45:18,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/34909044f614?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 15:45:19,654 unpacking tar archive at: autoland-34909044f614/testing/mozharness/ program finished with exit code 0 elapsedTime=1.512988 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 15:45:19.963490) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:19.963805) ========= script_repo_revision: 34909044f6148f65dbc3d0fbee5edf67d54c7f0f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:19.964201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:19.964480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 15:45:19.977034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-16 15:45:19.977381) ========= /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=9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:45:20 INFO - MultiFileLogger online at 20160816 15:45:20 in /builds/slave/test 15:45:20 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 15:45:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:45:20 INFO - {'append_to_log': False, 15:45:20 INFO - 'base_work_dir': '/builds/slave/test', 15:45:20 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:45:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:45:20 INFO - 'branch': 'Autoland-Non-PGO', 15:45:20 INFO - 'buildbot_json_path': 'buildprops.json', 15:45:20 INFO - 'config_files': ('talos/linux_config.py',), 15:45:20 INFO - 'default_actions': ('clobber', 15:45:20 INFO - 'read-buildbot-config', 15:45:20 INFO - 'download-and-extract', 15:45:20 INFO - 'populate-webroot', 15:45:20 INFO - 'create-virtualenv', 15:45:20 INFO - 'install', 15:45:20 INFO - 'run-tests'), 15:45:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:45:20 INFO - 'download_minidump_stackwalk': True, 15:45:20 INFO - 'download_symbols': 'ondemand', 15:45:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:45:20 INFO - 'tooltool.py': '/tools/tooltool.py', 15:45:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:45:20 INFO - '/tools/misc-python/virtualenv.py')}, 15:45:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:45:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:45:20 INFO - 'installer_path': 'installer.exe', 15:45:20 INFO - 'log_level': 'info', 15:45:20 INFO - 'log_name': 'talos', 15:45:20 INFO - 'log_to_console': True, 15:45:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:45:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:45:20 INFO - 'opt_config_files': (), 15:45:20 INFO - 'pip_index': False, 15:45:20 INFO - 'sps_profile': False, 15:45:20 INFO - 'sps_profile_interval': 0, 15:45:20 INFO - 'suite': 'dromaeojs', 15:45:20 INFO - 'system_bits': '32', 15:45:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:45:20 INFO - 'title': 'talos-linux64-ix-029', 15:45:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:45:20 INFO - 'use_talos_json': True, 15:45:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:45:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:45:20 INFO - 'work_dir': 'build'} 15:45:20 INFO - [mozharness: 2016-08-16 22:45:20.158179Z] Running clobber step. 15:45:20 INFO - Running pre-action listener: _resource_record_pre_action 15:45:20 INFO - Running main action method: clobber 15:45:20 INFO - rmtree: /builds/slave/test/build 15:45:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:45:20 INFO - Running post-action listener: _resource_record_post_action 15:45:20 INFO - [mozharness: 2016-08-16 22:45:20.605083Z] Finished clobber step (success) 15:45:20 INFO - [mozharness: 2016-08-16 22:45:20.605186Z] Running read-buildbot-config step. 15:45:20 INFO - Running pre-action listener: _resource_record_pre_action 15:45:20 INFO - Running main action method: read_buildbot_config 15:45:20 INFO - Using buildbot properties: 15:45:20 INFO - { 15:45:20 INFO - "project": "", 15:45:20 INFO - "product": "firefox", 15:45:20 INFO - "script_repo_revision": "production", 15:45:20 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:45:20 INFO - "repository": "", 15:45:20 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 15:45:20 INFO - "buildid": "20160816135115", 15:45:20 INFO - "pgo_build": "False", 15:45:20 INFO - "basedir": "/builds/slave/test", 15:45:20 INFO - "buildnumber": 288, 15:45:20 INFO - "slavename": "talos-linux64-ix-029", 15:45:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:45:20 INFO - "platform": "ubuntu64_hw", 15:45:20 INFO - "scheduler": "tests-autoland-linux64-talos-7-3600", 15:45:20 INFO - "branch": "autoland", 15:45:20 INFO - "repo_path": "integration/autoland", 15:45:20 INFO - "revision": "34909044f6148f65dbc3d0fbee5edf67d54c7f0f", 15:45:20 INFO - "stage_platform": "linux64", 15:45:20 INFO - "builduid": "c5d3de27e8b64ac8aeef51fcb85e32ba", 15:45:20 INFO - "slavebuilddir": "test" 15:45:20 INFO - } 15:45:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:45:20 INFO - Running post-action listener: _resource_record_post_action 15:45:20 INFO - [mozharness: 2016-08-16 22:45:20.607632Z] Finished read-buildbot-config step (success) 15:45:20 INFO - [mozharness: 2016-08-16 22:45:20.607720Z] Running download-and-extract step. 15:45:20 INFO - Running pre-action listener: _resource_record_pre_action 15:45:20 INFO - Running main action method: download_and_extract 15:45:20 INFO - mkdir: /builds/slave/test/build/tests 15:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:20 INFO - trying https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:45:20 INFO - Downloading https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:45:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:45:21 INFO - Downloaded 1361 bytes. 15:45:21 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:45:21 INFO - Using the following test package requirements: 15:45:21 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:45:21 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:45:21 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:45:21 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'jsshell-linux-x86_64.zip'], 15:45:21 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:45:21 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:45:21 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:45:21 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:45:21 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:45:21 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:21 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:45:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:21 INFO - trying https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:45:21 INFO - Downloading https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:45:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:22 INFO - Downloaded 20269096 bytes. 15:45:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 15:45:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:26 INFO - trying https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:45:26 INFO - Downloading https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:45:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:26 INFO - Downloaded 20269096 bytes. 15:45:26 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 15:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:31 INFO - trying https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:31 INFO - Downloading https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:45:32 INFO - Downloaded 14413719 bytes. 15:45:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 15:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:32 INFO - trying https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:45:32 INFO - Downloading https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:45:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:45:33 INFO - Downloaded 55645737 bytes. 15:45:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:45:33 INFO - mkdir: /builds/slave/test/properties 15:45:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:45:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:45:33 INFO - Contents: 15:45:33 INFO - build_url:https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:45:34 INFO - Running post-action listener: _resource_record_post_action 15:45:34 INFO - Running post-action listener: set_extra_try_arguments 15:45:34 INFO - [mozharness: 2016-08-16 22:45:34.435346Z] Finished download-and-extract step (success) 15:45:34 INFO - [mozharness: 2016-08-16 22:45:34.435570Z] Running populate-webroot step. 15:45:34 INFO - Running pre-action listener: _resource_record_pre_action 15:45:34 INFO - Running main action method: populate_webroot 15:45:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:45:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:45:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:45:34 INFO - '--tppagecycles', 15:45:34 INFO - '7'], 15:45:34 INFO - 'tests': ['tsvgm']}}, 15:45:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:45:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:45:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:45:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:45:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:45:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['damp', 'tps']}, 15:45:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'tests': ['damp', 'tps']}, 15:45:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['dromaeo_dom']}, 15:45:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:45:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['basic_compositor_video']}, 15:45:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:45:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['a11yr', 15:45:34 INFO - 'ts_paint', 15:45:34 INFO - 'tpaint', 15:45:34 INFO - 'sessionrestore', 15:45:34 INFO - 'sessionrestore_no_auto_restore', 15:45:34 INFO - 'tabpaint']}, 15:45:34 INFO - 'other-e10s': {'tests': ['a11yr', 15:45:34 INFO - 'ts_paint', 15:45:34 INFO - 'tpaint', 15:45:34 INFO - 'sessionrestore', 15:45:34 INFO - 'sessionrestore_no_auto_restore', 15:45:34 INFO - 'tabpaint']}, 15:45:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:45:34 INFO - 'ts_paint', 15:45:34 INFO - 'tpaint', 15:45:34 INFO - 'sessionrestore', 15:45:34 INFO - 'sessionrestore_no_auto_restore', 15:45:34 INFO - 'tabpaint']}, 15:45:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:45:34 INFO - 'ts_paint', 15:45:34 INFO - 'tpaint', 15:45:34 INFO - 'sessionrestore', 15:45:34 INFO - 'sessionrestore_no_auto_restore', 15:45:34 INFO - 'tabpaint']}, 15:45:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['a11yr', 15:45:34 INFO - 'ts_paint', 15:45:34 INFO - 'tpaint', 15:45:34 INFO - 'sessionrestore', 15:45:34 INFO - 'sessionrestore_no_auto_restore', 15:45:34 INFO - 'tabpaint']}, 15:45:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['a11yr', 15:45:34 INFO - 'ts_paint', 15:45:34 INFO - 'tpaint', 15:45:34 INFO - 'sessionrestore', 15:45:34 INFO - 'sessionrestore_no_auto_restore', 15:45:34 INFO - 'tabpaint']}, 15:45:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['tsvgx', 15:45:34 INFO - 'tsvgr_opacity', 15:45:34 INFO - 'tart', 15:45:34 INFO - 'tscrollx', 15:45:34 INFO - 'cart']}, 15:45:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:45:34 INFO - 'tsvgr_opacity', 15:45:34 INFO - 'tart', 15:45:34 INFO - 'tscrollx', 15:45:34 INFO - 'cart']}, 15:45:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'talos_options': ['--disable-e10s'], 15:45:34 INFO - 'tests': ['tp5o']}, 15:45:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'tests': ['tp5o']}, 15:45:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'talos_options': ['--disable-e10s', 15:45:34 INFO - '--xperf_path', 15:45:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:34 INFO - 'tests': ['tp5n']}, 15:45:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:45:34 INFO - 'talos_options': ['--xperf_path', 15:45:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:45:34 INFO - 'tests': ['tp5n']}}, 15:45:34 INFO - 'talos.zip': {'path': '', 15:45:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:45:34 INFO - Running post-action listener: _resource_record_post_action 15:45:34 INFO - [mozharness: 2016-08-16 22:45:34.457615Z] Finished populate-webroot step (success) 15:45:34 INFO - [mozharness: 2016-08-16 22:45:34.457703Z] Running create-virtualenv step. 15:45:34 INFO - Running pre-action listener: _resource_record_pre_action 15:45:34 INFO - Running main action method: create_virtualenv 15:45:34 INFO - Creating virtualenv /builds/slave/test/build/venv 15:45:34 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 15:45:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:45:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:45:34 INFO - Using real prefix '/usr' 15:45:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:45:35 INFO - Installing distribute.............................................................................................................................................................................................done. 15:45:37 INFO - Installing pip.................done. 15:45:37 INFO - Return code: 0 15:45:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:37 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:37 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 15:45:37 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 15:45:37 INFO - Using env: {'DISPLAY': ':0', 15:45:37 INFO - 'HOME': '/home/cltbld', 15:45:37 INFO - 'LANG': 'en_US.UTF-8', 15:45:37 INFO - 'LANGUAGE': 'en_US:en', 15:45:37 INFO - 'LOGNAME': 'cltbld', 15:45:37 INFO - 'MAIL': '/var/mail/cltbld', 15:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:37 INFO - 'MOZ_NO_REMOTE': '1', 15:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:37 INFO - 'NO_EM_RESTART': '1', 15:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:37 INFO - 'PWD': '/builds/slave/test', 15:45:37 INFO - 'SHELL': '/bin/bash', 15:45:37 INFO - 'SHLVL': '1', 15:45:37 INFO - 'TERM': 'linux', 15:45:37 INFO - 'TMOUT': '86400', 15:45:37 INFO - 'USER': 'cltbld', 15:45:37 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:37 INFO - '_': '/tools/buildbot/bin/python'} 15:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:37 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 15:45:37 INFO - Cleaning up... 15:45:37 INFO - Return code: 0 15:45:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:37 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:37 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 15:45:37 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 15:45:37 INFO - Using env: {'DISPLAY': ':0', 15:45:37 INFO - 'HOME': '/home/cltbld', 15:45:37 INFO - 'LANG': 'en_US.UTF-8', 15:45:37 INFO - 'LANGUAGE': 'en_US:en', 15:45:37 INFO - 'LOGNAME': 'cltbld', 15:45:37 INFO - 'MAIL': '/var/mail/cltbld', 15:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:37 INFO - 'MOZ_NO_REMOTE': '1', 15:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:37 INFO - 'NO_EM_RESTART': '1', 15:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:37 INFO - 'PWD': '/builds/slave/test', 15:45:37 INFO - 'SHELL': '/bin/bash', 15:45:37 INFO - 'SHLVL': '1', 15:45:37 INFO - 'TERM': 'linux', 15:45:37 INFO - 'TMOUT': '86400', 15:45:37 INFO - 'USER': 'cltbld', 15:45:37 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:37 INFO - '_': '/tools/buildbot/bin/python'} 15:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:37 INFO - Downloading/unpacking psutil>=3.1.1 15:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:45:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:39 INFO - Installing collected packages: psutil 15:45:39 INFO - Running setup.py install for psutil 15:45:39 INFO - building 'psutil._psutil_linux' extension 15:45:39 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 15:45:39 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 15:45:39 INFO - building 'psutil._psutil_posix' extension 15:45:39 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 15:45:39 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 15:45:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:45:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:45:39 INFO - Successfully installed psutil 15:45:39 INFO - Cleaning up... 15:45:39 INFO - Return code: 0 15:45:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:39 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:39 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 15:45:39 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 15:45:39 INFO - Using env: {'DISPLAY': ':0', 15:45:39 INFO - 'HOME': '/home/cltbld', 15:45:39 INFO - 'LANG': 'en_US.UTF-8', 15:45:39 INFO - 'LANGUAGE': 'en_US:en', 15:45:39 INFO - 'LOGNAME': 'cltbld', 15:45:39 INFO - 'MAIL': '/var/mail/cltbld', 15:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:39 INFO - 'MOZ_NO_REMOTE': '1', 15:45:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:39 INFO - 'NO_EM_RESTART': '1', 15:45:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:39 INFO - 'PWD': '/builds/slave/test', 15:45:39 INFO - 'SHELL': '/bin/bash', 15:45:39 INFO - 'SHLVL': '1', 15:45:39 INFO - 'TERM': 'linux', 15:45:39 INFO - 'TMOUT': '86400', 15:45:39 INFO - 'USER': 'cltbld', 15:45:39 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:39 INFO - '_': '/tools/buildbot/bin/python'} 15:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:45:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:45:42 INFO - Installing collected packages: mozsystemmonitor 15:45:42 INFO - Running setup.py install for mozsystemmonitor 15:45:42 INFO - Successfully installed mozsystemmonitor 15:45:42 INFO - Cleaning up... 15:45:42 INFO - Return code: 0 15:45:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:42 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:42 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 15:45:42 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 15:45:42 INFO - Using env: {'DISPLAY': ':0', 15:45:42 INFO - 'HOME': '/home/cltbld', 15:45:42 INFO - 'LANG': 'en_US.UTF-8', 15:45:42 INFO - 'LANGUAGE': 'en_US:en', 15:45:42 INFO - 'LOGNAME': 'cltbld', 15:45:42 INFO - 'MAIL': '/var/mail/cltbld', 15:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:42 INFO - 'MOZ_NO_REMOTE': '1', 15:45:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:42 INFO - 'NO_EM_RESTART': '1', 15:45:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:42 INFO - 'PWD': '/builds/slave/test', 15:45:42 INFO - 'SHELL': '/bin/bash', 15:45:42 INFO - 'SHLVL': '1', 15:45:42 INFO - 'TERM': 'linux', 15:45:42 INFO - 'TMOUT': '86400', 15:45:42 INFO - 'USER': 'cltbld', 15:45:42 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:42 INFO - '_': '/tools/buildbot/bin/python'} 15:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:42 INFO - Downloading/unpacking jsonschema==2.5.1 15:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:45:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:45:44 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 15:45:44 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 15:45:44 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 15:45:44 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 15:45:44 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 15:45:44 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 15:45:44 INFO - Downloading functools32-3.2.3-2.tar.gz 15:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:45:44 INFO - warning: no files found matching '*.txt' 15:45:44 INFO - no previously-included directories found matching 'build' 15:45:44 INFO - no previously-included directories found matching 'dist' 15:45:44 INFO - no previously-included directories found matching '.git*' 15:45:44 INFO - Installing collected packages: jsonschema, functools32 15:45:44 INFO - Running setup.py install for functools32 15:45:44 INFO - warning: no files found matching '*.txt' 15:45:44 INFO - no previously-included directories found matching 'build' 15:45:44 INFO - no previously-included directories found matching 'dist' 15:45:44 INFO - no previously-included directories found matching '.git*' 15:45:44 INFO - Successfully installed jsonschema functools32 15:45:44 INFO - Cleaning up... 15:45:44 INFO - Return code: 0 15:45:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:45:44 INFO - Using env: {'DISPLAY': ':0', 15:45:44 INFO - 'HOME': '/home/cltbld', 15:45:44 INFO - 'LANG': 'en_US.UTF-8', 15:45:44 INFO - 'LANGUAGE': 'en_US:en', 15:45:44 INFO - 'LOGNAME': 'cltbld', 15:45:44 INFO - 'MAIL': '/var/mail/cltbld', 15:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:44 INFO - 'MOZ_NO_REMOTE': '1', 15:45:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:44 INFO - 'NO_EM_RESTART': '1', 15:45:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:44 INFO - 'PWD': '/builds/slave/test', 15:45:44 INFO - 'SHELL': '/bin/bash', 15:45:44 INFO - 'SHLVL': '1', 15:45:44 INFO - 'TERM': 'linux', 15:45:44 INFO - 'TMOUT': '86400', 15:45:44 INFO - 'USER': 'cltbld', 15:45:44 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:44 INFO - '_': '/tools/buildbot/bin/python'} 15:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:45:45 INFO - Cleaning up... 15:45:45 INFO - Return code: 0 15:45:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:45 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:45 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 15:45:45 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 15:45:45 INFO - Using env: {'DISPLAY': ':0', 15:45:45 INFO - 'HOME': '/home/cltbld', 15:45:45 INFO - 'LANG': 'en_US.UTF-8', 15:45:45 INFO - 'LANGUAGE': 'en_US:en', 15:45:45 INFO - 'LOGNAME': 'cltbld', 15:45:45 INFO - 'MAIL': '/var/mail/cltbld', 15:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:45 INFO - 'MOZ_NO_REMOTE': '1', 15:45:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:45 INFO - 'NO_EM_RESTART': '1', 15:45:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:45 INFO - 'PWD': '/builds/slave/test', 15:45:45 INFO - 'SHELL': '/bin/bash', 15:45:45 INFO - 'SHLVL': '1', 15:45:45 INFO - 'TERM': 'linux', 15:45:45 INFO - 'TMOUT': '86400', 15:45:45 INFO - 'USER': 'cltbld', 15:45:45 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:45 INFO - '_': '/tools/buildbot/bin/python'} 15:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:45 INFO - Downloading/unpacking blobuploader==1.2.4 15:45:45 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 15:45:45 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 15:45:45 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 15:45:45 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 15:45:45 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 15:45:45 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 15:45:47 INFO - Downloading blobuploader-1.2.4.tar.gz 15:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:45:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:45:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 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 15:45:47 INFO - Downloading docopt-0.6.1.tar.gz 15:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:45:47 INFO - Installing collected packages: blobuploader, requests, docopt 15:45:47 INFO - Running setup.py install for blobuploader 15:45:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:45:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:45:48 INFO - Running setup.py install for requests 15:45:48 INFO - Running setup.py install for docopt 15:45:48 INFO - Successfully installed blobuploader requests docopt 15:45:48 INFO - Cleaning up... 15:45:48 INFO - Return code: 0 15:45:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:48 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:48 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 15:45:48 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 15:45:48 INFO - Using env: {'DISPLAY': ':0', 15:45:48 INFO - 'HOME': '/home/cltbld', 15:45:48 INFO - 'LANG': 'en_US.UTF-8', 15:45:48 INFO - 'LANGUAGE': 'en_US:en', 15:45:48 INFO - 'LOGNAME': 'cltbld', 15:45:48 INFO - 'MAIL': '/var/mail/cltbld', 15:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:48 INFO - 'MOZ_NO_REMOTE': '1', 15:45:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:48 INFO - 'NO_EM_RESTART': '1', 15:45:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:48 INFO - 'PWD': '/builds/slave/test', 15:45:48 INFO - 'SHELL': '/bin/bash', 15:45:48 INFO - 'SHLVL': '1', 15:45:48 INFO - 'TERM': 'linux', 15:45:48 INFO - 'TMOUT': '86400', 15:45:48 INFO - 'USER': 'cltbld', 15:45:48 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:48 INFO - '_': '/tools/buildbot/bin/python'} 15:45:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:48 INFO - Running setup.py (path:/tmp/pip-CyVU_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:48 INFO - Running setup.py (path:/tmp/pip-OpNDFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:48 INFO - Running setup.py (path:/tmp/pip-BT1FY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:48 INFO - Running setup.py (path:/tmp/pip-xxfuM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:49 INFO - Running setup.py (path:/tmp/pip-lFPjm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:49 INFO - Running setup.py (path:/tmp/pip-USD3_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:49 INFO - Running setup.py (path:/tmp/pip-oriIgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:49 INFO - Running setup.py (path:/tmp/pip-OkZsiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:49 INFO - Running setup.py (path:/tmp/pip-8H5MrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:49 INFO - Running setup.py (path:/tmp/pip-We2et9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:49 INFO - Running setup.py (path:/tmp/pip-KdbClX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:49 INFO - Running setup.py (path:/tmp/pip-t_Vlu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:49 INFO - Running setup.py (path:/tmp/pip-8KHlTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:49 INFO - Running setup.py (path:/tmp/pip-129j7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:49 INFO - Running setup.py (path:/tmp/pip-leTxr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:49 INFO - Running setup.py (path:/tmp/pip-DmZcyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:49 INFO - Running setup.py (path:/tmp/pip-GR_UUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:45:49 INFO - Running setup.py install for manifestparser 15:45:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Running setup.py install for mozcrash 15:45:50 INFO - Running setup.py install for mozdebug 15:45:50 INFO - Running setup.py install for mozdevice 15:45:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Running setup.py install for mozfile 15:45:50 INFO - Running setup.py install for mozhttpd 15:45:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Running setup.py install for mozinfo 15:45:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Running setup.py install for mozInstall 15:45:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:45:50 INFO - Running setup.py install for mozleak 15:45:50 INFO - Running setup.py install for mozlog 15:45:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Running setup.py install for moznetwork 15:45:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Running setup.py install for mozprocess 15:45:51 INFO - Running setup.py install for mozprofile 15:45:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Running setup.py install for mozrunner 15:45:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Running setup.py install for mozscreenshot 15:45:51 INFO - Running setup.py install for moztest 15:45:51 INFO - Running setup.py install for mozversion 15:45:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:45:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:45:51 INFO - Cleaning up... 15:45:51 INFO - Return code: 0 15:45:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:51 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:51 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 15:45:51 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 15:45:51 INFO - Using env: {'DISPLAY': ':0', 15:45:51 INFO - 'HOME': '/home/cltbld', 15:45:51 INFO - 'LANG': 'en_US.UTF-8', 15:45:51 INFO - 'LANGUAGE': 'en_US:en', 15:45:51 INFO - 'LOGNAME': 'cltbld', 15:45:51 INFO - 'MAIL': '/var/mail/cltbld', 15:45:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:51 INFO - 'MOZ_NO_REMOTE': '1', 15:45:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:51 INFO - 'NO_EM_RESTART': '1', 15:45:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:51 INFO - 'PWD': '/builds/slave/test', 15:45:51 INFO - 'SHELL': '/bin/bash', 15:45:51 INFO - 'SHLVL': '1', 15:45:51 INFO - 'TERM': 'linux', 15:45:51 INFO - 'TMOUT': '86400', 15:45:51 INFO - 'USER': 'cltbld', 15:45:51 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:51 INFO - '_': '/tools/buildbot/bin/python'} 15:45:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:51 INFO - Running setup.py (path:/tmp/pip-7k5aFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:52 INFO - Running setup.py (path:/tmp/pip-DwI4ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:52 INFO - Running setup.py (path:/tmp/pip-uhJ2S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:52 INFO - Running setup.py (path:/tmp/pip-XoZ8jc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:52 INFO - Running setup.py (path:/tmp/pip-GipqrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:52 INFO - Running setup.py (path:/tmp/pip-z4nAVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:52 INFO - Running setup.py (path:/tmp/pip-inGDjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:52 INFO - Running setup.py (path:/tmp/pip-qJgZs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:52 INFO - Running setup.py (path:/tmp/pip-nC8q7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:52 INFO - Running setup.py (path:/tmp/pip-Woyq_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:52 INFO - Running setup.py (path:/tmp/pip-jUHs3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:52 INFO - Running setup.py (path:/tmp/pip-zrmbgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:52 INFO - Running setup.py (path:/tmp/pip-4ks78Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:52 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)) 15:45:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:52 INFO - Running setup.py (path:/tmp/pip-lPxEkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:53 INFO - Running setup.py (path:/tmp/pip-Od_SwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:53 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)) 15:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:53 INFO - Running setup.py (path:/tmp/pip-biEKxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:53 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)) 15:45:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:53 INFO - Running setup.py (path:/tmp/pip-E57RTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:53 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)) 15:45:53 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)) 15:45:53 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)) 15:45:53 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)) 15:45:53 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)) 15:45:53 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)) 15:45:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45: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 15:45:55 INFO - Downloading blessings-1.6.tar.gz 15:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:55 INFO - Installing collected packages: blessings 15:45:55 INFO - Running setup.py install for blessings 15:45:55 INFO - Successfully installed blessings 15:45:55 INFO - Cleaning up... 15:45:55 INFO - Return code: 0 15:45:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:55 INFO - Reading from file tmpfile_stdout 15:45:55 INFO - Current package versions: 15:45:55 INFO - argparse == 1.2.1 15:45:55 INFO - blessings == 1.6 15:45:55 INFO - blobuploader == 1.2.4 15:45:55 INFO - docopt == 0.6.1 15:45:55 INFO - functools32 == 3.2.3-2 15:45:55 INFO - jsonschema == 2.5.1 15:45:55 INFO - manifestparser == 1.1 15:45:55 INFO - mozInstall == 1.12 15:45:55 INFO - mozcrash == 0.17 15:45:55 INFO - mozdebug == 0.1 15:45:55 INFO - mozdevice == 0.48 15:45:55 INFO - mozfile == 1.2 15:45:55 INFO - mozhttpd == 0.7 15:45:55 INFO - mozinfo == 0.9 15:45:55 INFO - mozleak == 0.1 15:45:55 INFO - mozlog == 3.3 15:45:55 INFO - moznetwork == 0.27 15:45:55 INFO - mozprocess == 0.23 15:45:55 INFO - mozprofile == 0.28 15:45:55 INFO - mozrunner == 6.12 15:45:55 INFO - mozscreenshot == 0.1 15:45:55 INFO - mozsystemmonitor == 0.3 15:45:55 INFO - moztest == 0.7 15:45:55 INFO - mozversion == 1.4 15:45:55 INFO - psutil == 3.1.1 15:45:55 INFO - requests == 1.2.3 15:45:55 INFO - wsgiref == 0.1.2 15:45:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45:55 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 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45:55 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 15:45:55 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 15:45:55 INFO - Using env: {'DISPLAY': ':0', 15:45:55 INFO - 'HOME': '/home/cltbld', 15:45:55 INFO - 'LANG': 'en_US.UTF-8', 15:45:55 INFO - 'LANGUAGE': 'en_US:en', 15:45:55 INFO - 'LOGNAME': 'cltbld', 15:45:55 INFO - 'MAIL': '/var/mail/cltbld', 15:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:55 INFO - 'MOZ_NO_REMOTE': '1', 15:45:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:55 INFO - 'NO_EM_RESTART': '1', 15:45:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:55 INFO - 'PWD': '/builds/slave/test', 15:45:55 INFO - 'SHELL': '/bin/bash', 15:45:55 INFO - 'SHLVL': '1', 15:45:55 INFO - 'TERM': 'linux', 15:45:55 INFO - 'TMOUT': '86400', 15:45:55 INFO - 'USER': 'cltbld', 15:45:55 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:55 INFO - '_': '/tools/buildbot/bin/python'} 15:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 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)) 15:45:55 INFO - Cleaning up... 15:45:55 INFO - Return code: 0 15:45:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:45: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd6276df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcfe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcd230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, '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': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', '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 15:45: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'] in /builds/slave/test/build 15:45: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 15:45:55 INFO - Using env: {'DISPLAY': ':0', 15:45:55 INFO - 'HOME': '/home/cltbld', 15:45:55 INFO - 'LANG': 'en_US.UTF-8', 15:45:55 INFO - 'LANGUAGE': 'en_US:en', 15:45:55 INFO - 'LOGNAME': 'cltbld', 15:45:55 INFO - 'MAIL': '/var/mail/cltbld', 15:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:55 INFO - 'MOZ_NO_REMOTE': '1', 15:45:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:45:55 INFO - 'NO_EM_RESTART': '1', 15:45:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:55 INFO - 'PWD': '/builds/slave/test', 15:45:55 INFO - 'SHELL': '/bin/bash', 15:45:55 INFO - 'SHLVL': '1', 15:45:55 INFO - 'TERM': 'linux', 15:45:55 INFO - 'TMOUT': '86400', 15:45:55 INFO - 'USER': 'cltbld', 15:45:55 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:55 INFO - '_': '/tools/buildbot/bin/python'} 15:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:56 INFO - Cleaning up... 15:45:56 INFO - Return code: 0 15:45:56 INFO - Running post-action listener: _resource_record_post_action 15:45:56 INFO - Running post-action listener: _start_resource_monitoring 15:45:56 INFO - Starting resource monitoring. 15:45:56 INFO - [mozharness: 2016-08-16 22:45:56.098797Z] Finished create-virtualenv step (success) 15:45:56 INFO - [mozharness: 2016-08-16 22:45:56.099258Z] Running install step. 15:45:56 INFO - Running pre-action listener: _resource_record_pre_action 15:45:56 INFO - Running main action method: install 15:45:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:56 INFO - Reading from file tmpfile_stdout 15:45:56 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:56 INFO - Reading from file tmpfile_stdout 15:45:56 INFO - Output received: 15:45:56 INFO - Usage: mozinstall [options] installer 15:45:56 INFO - Options: 15:45:56 INFO - -h, --help show this help message and exit 15:45:56 INFO - -d DEST, --destination=DEST 15:45:56 INFO - Directory to install application into. [default: 15:45:56 INFO - "/builds/slave/test"] 15:45:56 INFO - --app=APP Application being installed. [default: firefox] 15:45:56 INFO - mkdir: /builds/slave/test/build/application 15:45:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:46:12 INFO - Reading from file tmpfile_stdout 15:46:12 INFO - Output received: 15:46:12 INFO - /builds/slave/test/build/application/firefox/firefox 15:46:12 INFO - Running post-action listener: _resource_record_post_action 15:46:12 INFO - [mozharness: 2016-08-16 22:46:12.506141Z] Finished install step (success) 15:46:12 INFO - [mozharness: 2016-08-16 22:46:12.506389Z] Running run-tests step. 15:46:12 INFO - Running pre-action listener: _resource_record_pre_action 15:46:12 INFO - Running main action method: run_tests 15:46:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:46:12 INFO - Python 2.7.3 15:46:12 INFO - Return code: 0 15:46:12 INFO - grabbing minidump binary from tooltool 15:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:12 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 0xdc7090>, '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 0xdca9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdcae40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:46:12 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 15:46:12 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 15:46:12 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 15:46:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:46:12 INFO - Return code: 0 15:46:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:46:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:46:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:46:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:46:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:46:12 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/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 15:46:12 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/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 15:46:12 INFO - Using env: {'DISPLAY': ':0', 15:46:12 INFO - 'HOME': '/home/cltbld', 15:46:12 INFO - 'LANG': 'en_US.UTF-8', 15:46:12 INFO - 'LANGUAGE': 'en_US:en', 15:46:12 INFO - 'LOGNAME': 'cltbld', 15:46:12 INFO - 'MAIL': '/var/mail/cltbld', 15:46:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:12 INFO - 'MOZ_NO_REMOTE': '1', 15:46:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:12 INFO - 'NO_EM_RESTART': '1', 15:46:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:12 INFO - 'PWD': '/builds/slave/test', 15:46:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:12 INFO - 'SHELL': '/bin/bash', 15:46:12 INFO - 'SHLVL': '1', 15:46:12 INFO - 'TERM': 'linux', 15:46:12 INFO - 'TMOUT': '86400', 15:46:12 INFO - 'USER': 'cltbld', 15:46:12 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008', 15:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:12 INFO - '_': '/tools/buildbot/bin/python'} 15:46:12 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/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 15:46:12 INFO - mozversion application_buildid: 20160816135115 15:46:12 INFO - mozversion application_changeset: 34909044f6148f65dbc3d0fbee5edf67d54c7f0f 15:46:12 INFO - mozversion application_display_name: Nightly 15:46:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:46:12 INFO - mozversion application_name: Firefox 15:46:12 INFO - mozversion application_remotingname: firefox 15:46:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:46:12 INFO - mozversion application_vendor: Mozilla 15:46:12 INFO - mozversion application_version: 51.0a1 15:46:12 INFO - mozversion platform_buildid: 20160816135115 15:46:12 INFO - mozversion platform_changeset: 34909044f6148f65dbc3d0fbee5edf67d54c7f0f 15:46:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:46:12 INFO - mozversion platform_version: 51.0a1 15:46:12 INFO - using testdate: 1471387572 15:46:12 INFO - actual date: 1471387572 15:46:12 INFO - starting webserver on 'localhost:44087' 15:46:12 INFO - SUITE-START | Running 2 tests 15:46:12 INFO - TEST-START | dromaeo_css 15:46:12 INFO - operating with platform_type : linux_ 15:46:12 INFO - Initialising browser for dromaeo_css test... 15:46:12 INFO - TEST-INFO | started process 7250 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRT2uCt/profile http://localhost:44087/getInfo.html) 15:46:17 INFO - PROCESS | 7250 | __metrics Screen width/height:1600/1200 15:46:17 INFO - PROCESS | 7250 | colorDepth:24 15:46:17 INFO - PROCESS | 7250 | Browser inner width/height: 1024/697 15:46:17 INFO - PROCESS | 7250 | __metrics 15:46:18 INFO - PROCESS | 7250 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:46:18 INFO - PROCESS | 7250 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:46:19 INFO - TEST-INFO | 7250: exit 0 15:46:19 INFO - Browser initialized. 15:46:19 INFO - Running cycle 1/1 for dromaeo_css test... 15:46:19 INFO - TEST-INFO | started process 7330 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRT2uCt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:46:31 INFO - PROCESS | 7330 | RSS: Main: 156819456 15:46:31 INFO - PROCESS | 7330 | 15:47:22 INFO - PROCESS | 7330 | 1471387642320 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] 15:49:35 INFO - PROCESS | 7330 | Cycle 1(1): loaded http://localhost:44087/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44087/tests/dromaeo/cssquery-ext.html) 15:49:36 INFO - PROCESS | 7330 | RSS: Main: 244600832 15:49:36 INFO - PROCESS | 7330 | 15:52:39 INFO - PROCESS | 7330 | Cycle 1(1): loaded http://localhost:44087/tests/dromaeo/cssquery-ext.html (next: http://localhost:44087/tests/dromaeo/cssquery-jquery.html) 15:52:40 INFO - PROCESS | 7330 | RSS: Main: 233676800 15:52:40 INFO - PROCESS | 7330 | 15:55:42 INFO - PROCESS | 7330 | Cycle 1(1): loaded http://localhost:44087/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44087/tests/dromaeo/cssquery-mootools.html) 15:55:42 INFO - PROCESS | 7330 | RSS: Main: 189468672 15:55:42 INFO - PROCESS | 7330 | 15:58:45 INFO - PROCESS | 7330 | Cycle 1(1): loaded http://localhost:44087/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44087/tests/dromaeo/cssquery-prototype.html) 15:58:45 INFO - PROCESS | 7330 | RSS: Main: 189861888 15:58:45 INFO - PROCESS | 7330 | 16:01:48 INFO - PROCESS | 7330 | Cycle 1(1): loaded http://localhost:44087/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44087/tests/dromaeo/cssquery-yui.html) 16:01:49 INFO - PROCESS | 7330 | RSS: Main: 189849600 16:01:49 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | Cycle 1(1): loaded http://localhost:44087/tests/dromaeo/cssquery-yui.html (next: http://localhost:44087/tests/dromaeo/cssquery-dojo.html) 16:04:52 INFO - PROCESS | 7330 | RSS: Main: 282525696 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | __start_tp_report 16:04:52 INFO - PROCESS | 7330 | _x_x_mozilla_page_load 16:04:52 INFO - PROCESS | 7330 | _x_x_mozilla_page_load_details 16:04:52 INFO - PROCESS | 7330 | |i|pagename|runs| 16:04:52 INFO - PROCESS | 7330 | |0;dojo.html;2657.8006649501285;2687.5968604709306;2702.986485067575;2716.212298433454;2720.415110751188;4592.72443653381;4639;4738.21819399799;4791.161546729321;4798.304245884345;4690.90618187636;4697.624190064796;4764.928526072112;4779.498152693969;4787.760611969405;4301.784910754465;4347.956520434803;4386.71486353386;4432.601065904068;4526.886827829314;4421.115776844628;4463.129689710508;4480.350349199364;4504.099180163964;4505.189065968125;4287.764172970486;4335;4339.305711086243;4415.580519850641;4419.513853476147;4326.199653064162;4341.826326946918;4351.6518678505645;4410.691251612357;4417.624501917308;4305.20353734557;4342.934855977162;4350.325699516548;4351.456067991501;4393.758343291118;4590.495045544987;4744.691595046311;4774.689645173053;4804.159272127398;4813.085513752376;4959.578435832958;5031.47169547196;5035.3454050973505;5108.923366149511;5111.4121875984365;5080.59355251579;5102.056119617882;5130.948690513084;5142.151544995071;5151.227315902607;4649.511801260886;4714.222153344728;4792.2811578262845;4797.616190704735;4799.0401919616215;5186.507281808223;5239.73801309933;5268.315119034501;5287.048331300402;5293.126634105406;5023.949760502422;5027.044861476308;5061.9876024795185;5065.138926382524;5065.645404821701;4989.401271847401;5053.393592768891;5101.081805275012;5116.437191908887;5157.742112894341;5007.574356179693;5049.924251136236;5062.088824011639;5104;5109.335786347751;5022.799088036438;5037.143685573461;5044.092063428545;5048.798048078036;5089.033083714083;4140.068484590943;4173.791310434466;4260.893477663083;4269.082147338338;4310.504292006397;3703.870364537282;3724.273766615484;3734.5705243896755;3765.4540091686554;3788.9431658525145;4472.44094488189;4474.485434175046;4476.776161191928;4505.504394516601;4520.841770538082;5179.559737422362;5181.766820493011;5185.351831021122;5195;5236.502532259429;4331.523532411432;4418.4918734345965;4459.397981272552;4473.642108631302;4539.16025535277;4593.425821772278;4624.95375046252;4625.814967401334;4626.74552899587;4671.43942726875;4841.273808928689;4862.975685121552;4873.439554451284;4876.756162191948;4917.041176970456;4976.452590215146;4994.550490455731;5015.924761128513;5039.647224694236;5125.128728116155;4748.240281554935;4770.451398089264;4777;4779.378680771478;4824;4978.2034874419915;4983.426905905867;4984.127777638972;4985.376827896513;5028.321176641107;4956.504349565014;4977.203647416396;5021.372328458942;5027.84916452507;5094.5669618082875;4937.185364414975;4943.876403089952;4947.109520286239;4954.603631709382;4999.600031997505;4940.580050695731;4944.431390390151;4944.53026962438;4952.182889823139;4991.201407774884;4789.377380940595;4824.228123500223;4870.318155458168;4873.488283730299;4912.189488734319;4940.7035577865445;4972.229304457815;4979.278004689372;4987.850364489072;5016.799328026838;4873.975630121827;4875.829345972874;4880.194767863263;4881.438634557071;4934.802607895644 16:04:52 INFO - PROCESS | 7330 | |1;ext.html;556.5464146720423;558.3076984539173;572.5762935427784;574.9396313387095;579.0041129257678;28797.280067998312;29328.26679333018;41664.37503437443;44070.33894491585;44137.33793993093;40147.59852401475;40551.594484055226;40554;40743.59256407435;40893.79553102238;7601.619919004055;7739.922600774004;7745;7746.302832745052;7748.225177482235;7695.769126926201;7781.105172905131;7783.182765809594;7783.688652453895;7789.454738168331;7765.534067955912;7818.061832580099;7825.100113486965;7831.29518343349;7833.216678332178;7713.112992005935;7745.922540774576;7747.573883436408;7783.494072885301;7796.571188584626;7707.6916923323;7782;7789.766307010742;7791.181925897756;7791.22087791222;38458.615413845786;38747;38833.417498737545;38898;38900.80549597262;3063.402636485886;3074.984625076883;3078.722914937644;3331;3369.949450758241;29194.70805291941;29652;30159.396812063856;30219.848900755576;30759.69240307613;3333.3166700826273;3556.8043757593323;3564.803935783531;3614.4036234021355;3615.8011309378235;204229;257296;258052;259182.70408648025;259573;33214.16964575905;34336.82831585826;54779.452205477435;55157;55237;33475.49786753201;33806.830965845504;33809.492857607154;33816.32367352639;33829.49255761166;10722.855428914248;10765.78468430627;10767.515461804238;10779.922007799158;10782.78434431307;8021.157778433298;8022.2779949803635;8028.558429286329;8031.196880311922;8083.959580202061;7801.7907224380315;7866.134725180225;7875.251851074253;7932.127465978738;7957.323627491728;3008.578798968146;3014;3017.8038427502142;3033.8634761435787;3124.3282694220397;870.6604424274537;892.4868200784445;913.1918252346721;972.8783902012249;987.6592575561419;27305;27560.310992224953;27736.583951240747;28118.85940570325;28618.570721439195;924.4822899176482;964.5996911281879;965.7151140413595;994.9005099490138;1024.9795004100026;14691.191984440744;14704.779428308584;14826.258687065603;15076;15125.621859453382;385543;386737;387377;388323;388781;49670.75164624154;50144.49855501471;50245.74877125591;50308.49691503038;50424;1064.8189807732704;1118.798616249083;1179.8997783944592;1184.810430331143;1186.1874615888144;27362.72637273602;28475;29369.07035100468;29648.258793530335;29742;41486.58513414948;42011.579884200764;42085.789571053174;42108;42165.57834421739;290566;291361;291794;291932;292078;22941.770582293964;24605.87697061503;25476.108336208144;25478;25587.744122558535;40806;41135;41301.1739765197;41371.379429307985;42185;35585.64414355823;35609.82195089008;35642.40615486143;35766.28467430585;36365.81817090898;35072.29855402929;35408.64591354054;35579.46630800592;35589.46615800713;35902.461463078595;34396.65603343934;34861.30277394489;35128.47307290341;35422.645773542965;36073.45889811603;30764.692353077076;32456.675433245364;33122.83438582792;33168;33403.498947516295;24341.51316973615;24925.252242432754;25105.74894251034;25223.62164567496;25617.87191064107 16:04:52 INFO - PROCESS | 7330 | |2;jquery.html;347.4007337343082;360.2093404976075;361.8064335580465;362.8856910073327;374.1152175105874;15902.443414480496;17114.144292785357;17355.045472499023;17462.301507939697;17467.737983930252;15781.684366312667;16500.66998660026;16526.586835329123;16975.83024169761;17068.914655426706;4730.550597693222;4948.925766113512;4958.355413796201;4964.280179373988;4967.0810899983435;3633.073566240618;3826.4642949987024;3827.3684842001035;3872.632099950514;4910.361652985107;3906.9609303907023;5840.561957853157;5853.473187413132;5937.85155371117;5946.048632218846;5774.162746401748;5800.216970708942;5918.644881307114;5931.228940237763;5934.762609495615;5191.169412893919;5207.791688332462;5208.062548741225;5208.427073021964;5214.217867319894;20073.29743458972;20111.79888201114;20509.2821751238;20599.691004634944;20799.16803327865;1875.9155837987323;1917.587718640499;1949.191085699433;1968.3996381103757;2520.7479252074827;12649.557265495663;13028.934855325757;13384.263865487394;13472.326383680776;13909.721805563933;1694.3561446650317;1765.9028753418556;1809.1677828199045;1816.0919540229886;1851.8240767127102;60405;70293.29706702918;70723.29276707218;72105;73067.63466182741;10072.244581656405;10086.546105425274;10094.899051009543;10102.949485252526;10343.327683700536;13701.794473082911;13741.519046833513;13747.106438081493;13760.587182384545;13767.173969561858;6709.094272273278;6846.863062738717;6958.512904096766;6970.965145174242;6980.616066116412;6309.274433440121;6316.084167795644;6323.20959880015;6448.904525827629;6487.837804054936;4356.49900261474;4358.542353052947;4377.956220437755;4399.780010999437;4464.866054018385;1835.8347748702413;1863.4782260966952;1864.792556802298;1886.76415448069;1908.1318000309825;775.7944144801633;811.9512829230265;859.750672305037;861.4185424838209;897.5512243878061;16054.518364449084;16326;17158.48524544266;17264.913675431544;17360.218790154475;811.123986095016;814.4054839966739;827.5655280977414;836.3977935886152;846.7205822078699;8815.559222038872;9162.175404213653;9605.519724013771;9620.182284505896;9622.13400793918;382822;383056;383123;387973;392482;74100.62949685325;75917;76379;77365.6131719349;77919;877.9429337093069;906.7370462565782;934.8270569944582;936.5879013234292;937.3438592984937;15278.555431130966;15281.6179595511;15998.68002639964;16128.354865805237;16214.10822404779;36836.26327473382;37075;38062.23875522529;38117.80941095277;38222.42666360062;52180.21729673982;52208.21687674764;52252.73873630608;54265.72867135639;55015;7340.449466289942;7533.359664428366;7570.507916985378;7592.392608591189;7639.388848892187;10164.052424796726;10235.692929212135;10524.473776311306;10572.471376431302;11427.371494567546;9202.677906273248;9620.326577139813;9753.951230243803;9881.407115573089;10087.89912100899;9239;9303.581338839633;9713;9793.069658382443;10048.648297309852;9680;9690.951545242511;9989.700308990741;10181.694549163536;10546.314489558461;6455.193100862392;6530.771423000362;6586.50601204902;6978.09284792994;7207.171175314906;7781.727639532589;9472.57373418184;9483.48687597801;9893.307468477138;10016.949915250378 16:04:52 INFO - PROCESS | 7330 | |3;mootools.html;1213.460010295419;1306.2619619914747;1355.5526676196853;1393.5749596373103;1641.7619445180442;6611.966940165298;7407.296306850847;7544.962275188624;7546.69813207471;7842.058952925657;7576.015118034662;7608.657610407531;7623;7630.122535908372;7640.96179519103;2171.9565608687817;2190.1567896359857;2190.4304880731047;2190.9561808763815;2193.605151072806;2116;2165.8917054147228;2207.8896055197174;2211.8009379155874;2213.1258153029544;2158.805707486326;2176.564687062586;2179.3570896585547;2193.4187440328246;2203.8787866667326;2116.2593092417683;2123.7239158909397;2131.2221039320616;2142.882141482218;2146.066461089431;2079.116375540389;2117.4812171018043;2117.8623389479635;2119.5125121222204;2120.724305840231;7602.619869006583;7684.154742978268;7691.461597688164;7711.884321735179;7719;4341.891452713708;4500.5274446183;4510.977445112786;4545.886352841206;4568.383268258809;2702.4189799193073;2811.578263260507;2816;2824.09628918744;2828.08087371603;953.5708930981017;965.290511474066;968.4818622037176;973.7078876337141;978.1538968792015;10362.171026317876;10450.634227801991;10474.79050418995;10481.737956551071;10491.213159013028;3962.7424217425773;4126.66986641068;4159.771212583277;4287.592678695519;4302.419173411612;8130.390220733469;8297.792555186168;8307.37694672902;8312.958435207785;8321.833563328699;4425.845095421645;4432.559418189101;4434.024514606781;4436.578525040116;4437.822487100544;3601.1897323102617;3709.9072523187137;4079.1229885574744;4081.142959978438;4103.9179216415505;3289.259916518812;3298.1589694627714;3380.087376408356;3384.7799893006877;3396.4226081566194;1024.6044469227081;1034.813733527957;1043.5982146873357;1044.0916402729522;1054.3146954479496;661.5501459007825;761.9161892191854;770.603139383218;790.4782843323379;799.564237490569;4659.6505262105475;4953.653244272867;4974.527419895104;5097.337346144977;5123.487651234921;800.4036992440668;818.8526065308301;824.6536454689045;832.367400775403;872.3152325424511;3283.7701360904985;3399.2521645238007;3405.4721518164724;3445.155936795451;3517.2086280586664;9098.818023639491;9693.466859322805;9701.17540009093;9710.514474276399;9722.951385243028;9423.434593924387;9604.375715578464;9612.855807162898;9617.471039092778;9667.70996870095;7123.358897699232;7272.163701174432;7277.781666550012;7299.4160467163565;7311.561306321638;4927.334809800631;4944.604431645388;4957;4985.8005679772405;5169.4830516949505;8910.420822646507;8957.910420895707;8959.641614335354;8967;8970.192682658331;7934.087579928382;8818.735437936872;8953.552322383724;8956.68651597191;9062.04848490899;6082.60463069899;6282.968585157045;6328.398802113792;6419.550631455753;6428.582142160744;7386.15059268191;7405.9629701851145;7434;7443.069616297963;7467.141278752796;6495.512836537373;6583.506237032146;6641.169853768279;6715.664216789239;6737.124173857367;6306.842328941813;6340.9365906340345;6342.048692696132;6460.12788273577;6473.935260647333;6305.96847015762;6342.492600592034;6358.682065896779;6403.423691867754;6477.708503117276;4178.456800615901;4367.781610919505;4408.559144085566;4498.347739577677;4629.953700462952;4298.075913678574;4341.913161736811;4413.073254616438;4479.3057076153245;4616.446026476844 16:04:52 INFO - PROCESS | 7330 | |4;prototype.html;283.70069576596694;287.54424237583424;292.6561290483681;293.186407718581;300.3062924644071;4687.250039993606;4791.401074865633;4820.999999999995;4840;4867.172580661288;4764.499727528611;4775.856724298268;4778.378810754597;4791.496892826255;4803.663743537953;3567.7680950738118;3616.27674465108;3698.5191925049708;3769.4157405602177;3804.619538046201;3762.604926482721;3768.9811550942345;3770.1705624762644;3772.1512659651635;3779.5086638736925;3733.4586484959664;3735.122246272124;3735.140917588956;3737.3272810894027;3737.6075512071247;3559.626239244894;3605.981970090159;3687.3362794696945;3726.7950262735403;3734.906627334311;3297.4229509835923;3307.057488615757;3313.4698448248405;3316.2041110133637;3325.833708314575;4642.930356044662;4645.978999135353;4646.724059538017;4661.510541393139;4683.508231635663;4158.812853421603;4268.59448352406;4273.059926816096;4275.679324050709;4278.165757677254;4958.776855041532;4960.975195124038;4969.478204788481;4978.00439912019;4987.700737955734;3609.3322735294055;3612.169201083739;3614.493970844084;3616.9978917498674;3620.2216523447323;5167.8114033772445;5171.5087066728975;5187.507186817246;5198.194279886624;5213.582913366921;3588.7618771523785;3667.2848794485353;3688.9077773055888;3697.500837386918;3702.2965636528975;3739.2147648993473;3745.7190710696805;3747.775133491999;3748.5876553579083;3754.136548593784;4254.914901701949;4256.382824490432;4256.638185754184;4256.87229383119;4259.424977628042;4781.2350023996;4954.9009019819405;4973.353464049723;4984.177610694195;4990.725510096907;1279.1110178425904;1292.3150730113055;1296.371259938929;1303.8200428612122;1308.3981368570373;801.2228138705447;833.4581025103631;837.3175861672718;839.3369238301673;844.1305455380967;4256.936145957813;4409.294512877988;4480.731156130642;4512.977435112869;4519.7740112994215;5187.118189907725;5190.92213616796;5197.58419326453;5204.9739751301;5208.270842082035;4444.911101777946;4502.887427814266;4502.954970450253;4512.684112112121;4521.535763009168;2579.0328626764963;2722.68689100752;2724.305302147979;2728.836269823817;2731.7404846539944;4864.95135048645;4876.853694389174;4891.706497610155;4910.263460480956;4911.926321105187;5146.588272938156;5281.155015197627;5282.811367442295;5285;5310.734463276821;4892.510748925079;4903.901921961612;4907.55831975124;4926.285688575136;4942.629302802232;5183.948160518346;5186.974065129651;5191.8182863599595;5196.376434827845;5197.948020519898;4973.303737476684;5114.130597798308;5115.565176959852;5116.386033675983;5119.6416250862085;4881.389826271716;4888.633352498506;4897.314375987294;4917.508249175054;4918.458969513422;3743.887683369503;3758.755680880734;3763.3414152523746;3794.8292326845913;3797.2025874566652;4918.901621967612;5147.897042059213;5219.5041471060185;5221.660592061504;5221.765020574159;4984.576311013604;4991.401031876198;5028.421731500778;5033.144365457954;5045.949540504548;5030.346055012971;5030.647854650139;5037.672551284155;5051.747412629427;5065.974670126773;4808.083615393009;4857.975710121426;4908;4975.228839529878;4985.775640096132;4835;4837.05677392905;5002.424721157114;5005.449400566008;5058.64589478733;4921.50784921505;4939.851804445872;4943.925841112315;4952.529509696573;4959.876003099807 16:04:52 INFO - PROCESS | 7330 | |5;yui.html;605.1030020884527;627.131422979174;638.3010603389287;674.8144260328411;715.0811207598231;12567.560135395262;14515.782263266059;14590.708185836276;14609.707805843876;14784.482543110993;13788;13875.444982200725;13919.582412527641;13996.860031399683;14007.509737159176;633.0472638678782;763.7479631721519;782.5774081995716;785.7917651822272;785.9528428294292;745.8396444764375;771.0131032278692;772.3859531672306;779.6530543907963;780.4614815777096;827.1149869639488;828.6395417993162;828.9502629842228;829.357248132696;830.2610676497922;648.4876947211698;821.2526600793279;822.4777266435797;822.5517093184199;823.5100115431309;582.4292193650247;594.6491569973737;595.0538643556722;595.5176307191189;595.5920194666667;13155.86844131556;13179.86820131796;13180.340982950815;13209;13241.337933103307;12301.384930753427;12584.68538286541;12851.293178875158;13497.055206135574;13688.52090176849;11987.340696261703;12017.158798884082;12180.269183848908;12337.568185113567;12534.623961281177;4040.8181631826637;4103;4267.167902259061;4277.144571085795;4279.935800962988;64699.67650161766;65221;65257;65447;66232.668836656;10613.097886679565;10906.836397454044;10982.780344393068;10993.34039957589;10998.450077496092;19173.945433001318;19240.134193961025;19328.323508677127;19411.3206037788;19426.125824337938;6806.285340039323;6855.414518048433;7016.228214896358;7080.256573059857;7232.276772322725;3595.388783906742;3625.691816195653;3773.772480092259;3777.433384992273;3879.670228030649;2706.215197592676;2825.4631619992138;2825.6891741908785;2828.7029861864207;2831.40540486496;538.1658429434361;555.3225065420181;564.604776656334;566.3656704490996;570.9429057094258;495.091507084504;497.71381455662413;557.2338035201598;568.7213265499958;604.7361015477672;7738.806529836686;7866.331361834308;7867.960660196777;7951.602419878983;8071.152528984489;526.7919171927144;527.2565682387891;544.5806728818787;600.3996003996004;606.2149516376303;3503.386907291214;3674.1549443628114;3684.9078773030888;3885.747426417274;3934.488516492838;99917;104873.47563262135;107210.46394767976;107288.46355768171;108527;54046.729766350916;73240;89226;90128.54935725279;90956.54521727482;61678;74759.62620186864;79601.60199198966;79606;81157.59421202856;7023.297670232936;7156.821079473055;7229.421646268181;7348.77953661391;7502.962485187539;33162.83418582891;33513.83243083769;42913.57086429096;44389;47146.29280560863;61219;68630;68637.97043044258;68823.31176688168;71955;9662.71011869645;9866.851997219905;9872.753181170241;10060.899391005996;10204.795904082026;10393.116585090049;10419.739506512398;11104.167187460811;11203.327800332007;11352.375619341015;9841.901580984098;10055.74860628461;10075.848862266925;10391.740206494898;10602.46987650599;9500.144986951207;9536.904630953602;9603.951980240334;9927.503624818586;10188.235882308709;9773.804523909339;10215.438150901475;10401.167906567607;10415.42715150674;10439.37363758177;8664.523451210243;8852.867206992029;8954.507502087186;9154.359194856295;9327.486988215442;8106.189381061847;8494.655878533564;8602.956985215034;8810.911890881009;8920.152585504367 16:04:52 INFO - PROCESS | 7330 | __end_tp_report 16:04:52 INFO - PROCESS | 7330 | __start_cc_report 16:04:52 INFO - PROCESS | 7330 | _x_x_mozilla_cycle_collect,1046 16:04:52 INFO - PROCESS | 7330 | __end_cc_report 16:04:52 INFO - PROCESS | 7330 | __startTimestamp1471388692795__endTimestamp 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | ------- Summary: start ------- 16:04:52 INFO - PROCESS | 7330 | Number of tests: 6 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | [#0] dojo.html Cycles:165 Average:4707.03 Median:4841.27 stddev:487.54 (10.1%) stddev-sans-first:461.77 16:04:52 INFO - PROCESS | 7330 | Values: 2657.8 2687.6 2703.0 2716.2 2720.4 4592.7 4639.0 4738.2 4791.2 4798.3 4690.9 4697.6 4764.9 4779.5 4787.8 4301.8 4348.0 4386.7 4432.6 4526.9 4421.1 4463.1 4480.4 4504.1 4505.2 4287.8 4335.0 4339.3 4415.6 4419.5 4326.2 4341.8 4351.7 4410.7 4417.6 4305.2 4342.9 4350.3 4351.5 4393.8 4590.5 4744.7 4774.7 4804.2 4813.1 4959.6 5031.5 5035.3 5108.9 5111.4 5080.6 5102.1 5130.9 5142.2 5151.2 4649.5 4714.2 4792.3 4797.6 4799.0 5186.5 5239.7 5268.3 5287.0 5293.1 5023.9 5027.0 5062.0 5065.1 5065.6 4989.4 5053.4 5101.1 5116.4 5157.7 5007.6 5049.9 5062.1 5104.0 5109.3 5022.8 5037.1 5044.1 5048.8 5089.0 4140.1 4173.8 4260.9 4269.1 4310.5 3703.9 3724.3 3734.6 3765.5 3788.9 4472.4 4474.5 4476.8 4505.5 4520.8 5179.6 5181.8 5185.4 5195.0 5236.5 4331.5 4418.5 4459.4 4473.6 4539.2 4593.4 4625.0 4625.8 4626.7 4671.4 4841.3 4863.0 4873.4 4876.8 4917.0 4976.5 4994.6 5015.9 5039.6 5125.1 4748.2 4770.5 4777.0 4779.4 4824.0 4978.2 4983.4 4984.1 4985.4 5028.3 4956.5 4977.2 5021.4 5027.8 5094.6 4937.2 4943.9 4947.1 4954.6 4999.6 4940.6 4944.4 4944.5 4952.2 4991.2 4789.4 4824.2 4870.3 4873.5 4912.2 4940.7 4972.2 4979.3 4987.9 5016.8 4874.0 4875.8 4880.2 4881.4 4934.8 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | [#1] ext.html Cycles:180 Average:45188.31 Median:35987.96 stddev:83022.46 (230.7%) stddev-sans-first:83187.73 16:04:52 INFO - PROCESS | 7330 | Values: 556.5 558.3 572.6 574.9 579.0 28797.3 29328.3 41664.4 44070.3 44137.3 40147.6 40551.6 40554.0 40743.6 40893.8 7601.6 7739.9 7745.0 7746.3 7748.2 7695.8 7781.1 7783.2 7783.7 7789.5 7765.5 7818.1 7825.1 7831.3 7833.2 7713.1 7745.9 7747.6 7783.5 7796.6 7707.7 7782.0 7789.8 7791.2 7791.2 38458.6 38747.0 38833.4 38898.0 38900.8 3063.4 3075.0 3078.7 3331.0 3369.9 29194.7 29652.0 30159.4 30219.8 30759.7 3333.3 3556.8 3564.8 3614.4 3615.8 204229.0 257296.0 258052.0 259182.7 259573.0 33214.2 34336.8 54779.5 55157.0 55237.0 33475.5 33806.8 33809.5 33816.3 33829.5 10722.9 10765.8 10767.5 10779.9 10782.8 8021.2 8022.3 8028.6 8031.2 8084.0 7801.8 7866.1 7875.3 7932.1 7957.3 3008.6 3014.0 3017.8 3033.9 3124.3 870.7 892.5 913.2 972.9 987.7 27305.0 27560.3 27736.6 28118.9 28618.6 924.5 964.6 965.7 994.9 1025.0 14691.2 14704.8 14826.3 15076.0 15125.6 385543.0 386737.0 387377.0 388323.0 388781.0 49670.8 50144.5 50245.7 50308.5 50424.0 1064.8 1118.8 1179.9 1184.8 1186.2 27362.7 28475.0 29369.1 29648.3 29742.0 41486.6 42011.6 42085.8 42108.0 42165.6 290566.0 291361.0 291794.0 291932.0 292078.0 22941.8 24605.9 25476.1 25478.0 25587.7 40806.0 41135.0 41301.2 41371.4 42185.0 35585.6 35609.8 35642.4 35766.3 36365.8 35072.3 35408.6 35579.5 35589.5 35902.5 34396.7 34861.3 35128.5 35422.6 36073.5 30764.7 32456.7 33122.8 33168.0 33403.5 24341.5 24925.3 25105.7 25223.6 25617.9 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | [#2] jquery.html Cycles:180 Average:24193.33 Median:4820.46 stddev:63789.73 (1323.3%) stddev-sans-first:63943.55 16:04:52 INFO - PROCESS | 7330 | Values: 347.4 360.2 361.8 362.9 374.1 15902.4 17114.1 17355.0 17462.3 17467.7 15781.7 16500.7 16526.6 16975.8 17068.9 4730.6 4948.9 4958.4 4964.3 4967.1 3633.1 3826.5 3827.4 3872.6 4910.4 3907.0 5840.6 5853.5 5937.9 5946.0 5774.2 5800.2 5918.6 5931.2 5934.8 5191.2 5207.8 5208.1 5208.4 5214.2 20073.3 20111.8 20509.3 20599.7 20799.2 1875.9 1917.6 1949.2 1968.4 2520.7 12649.6 13028.9 13384.3 13472.3 13909.7 1694.4 1765.9 1809.2 1816.1 1851.8 60405.0 70293.3 70723.3 72105.0 73067.6 10072.2 10086.5 10094.9 10102.9 10343.3 13701.8 13741.5 13747.1 13760.6 13767.2 6709.1 6846.9 6958.5 6971.0 6980.6 6309.3 6316.1 6323.2 6448.9 6487.8 4356.5 4358.5 4378.0 4399.8 4464.9 1835.8 1863.5 1864.8 1886.8 1908.1 775.8 812.0 859.8 861.4 897.6 16054.5 16326.0 17158.5 17264.9 17360.2 811.1 814.4 827.6 836.4 846.7 8815.6 9162.2 9605.5 9620.2 9622.1 382822.0 383056.0 383123.0 387973.0 392482.0 74100.6 75917.0 76379.0 77365.6 77919.0 877.9 906.7 934.8 936.6 937.3 15278.6 15281.6 15998.7 16128.4 16214.1 36836.3 37075.0 38062.2 38117.8 38222.4 52180.2 52208.2 52252.7 54265.7 55015.0 7340.4 7533.4 7570.5 7592.4 7639.4 10164.1 10235.7 10524.5 10572.5 11427.4 9202.7 9620.3 9754.0 9881.4 10087.9 9239.0 9303.6 9713.0 9793.1 10048.6 9680.0 9691.0 9989.7 10181.7 10546.3 6455.2 6530.8 6586.5 6978.1 7207.2 7781.7 9472.6 9483.5 9893.3 10016.9 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | [#3] mootools.html Cycles:180 Average:4994.89 Median:4980.16 stddev:2825.01 (56.7%) stddev-sans-first:2818.64 16:04:52 INFO - PROCESS | 7330 | Values: 1213.5 1306.3 1355.6 1393.6 1641.8 6612.0 7407.3 7545.0 7546.7 7842.1 7576.0 7608.7 7623.0 7630.1 7641.0 2172.0 2190.2 2190.4 2191.0 2193.6 2116.0 2165.9 2207.9 2211.8 2213.1 2158.8 2176.6 2179.4 2193.4 2203.9 2116.3 2123.7 2131.2 2142.9 2146.1 2079.1 2117.5 2117.9 2119.5 2120.7 7602.6 7684.2 7691.5 7711.9 7719.0 4341.9 4500.5 4511.0 4545.9 4568.4 2702.4 2811.6 2816.0 2824.1 2828.1 953.6 965.3 968.5 973.7 978.2 10362.2 10450.6 10474.8 10481.7 10491.2 3962.7 4126.7 4159.8 4287.6 4302.4 8130.4 8297.8 8307.4 8313.0 8321.8 4425.8 4432.6 4434.0 4436.6 4437.8 3601.2 3709.9 4079.1 4081.1 4103.9 3289.3 3298.2 3380.1 3384.8 3396.4 1024.6 1034.8 1043.6 1044.1 1054.3 661.6 761.9 770.6 790.5 799.6 4659.7 4953.7 4974.5 5097.3 5123.5 800.4 818.9 824.7 832.4 872.3 3283.8 3399.3 3405.5 3445.2 3517.2 9098.8 9693.5 9701.2 9710.5 9723.0 9423.4 9604.4 9612.9 9617.5 9667.7 7123.4 7272.2 7277.8 7299.4 7311.6 4927.3 4944.6 4957.0 4985.8 5169.5 8910.4 8957.9 8959.6 8967.0 8970.2 7934.1 8818.7 8953.6 8956.7 9062.0 6082.6 6283.0 6328.4 6419.6 6428.6 7386.2 7406.0 7434.0 7443.1 7467.1 6495.5 6583.5 6641.2 6715.7 6737.1 6306.8 6340.9 6342.0 6460.1 6473.9 6306.0 6342.5 6358.7 6403.4 6477.7 4178.5 4367.8 4408.6 4498.3 4630.0 4298.1 4341.9 4413.1 4479.3 4616.4 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | [#4] prototype.html Cycles:180 Average:4175.45 Median:4791.45 stddev:1211.57 (25.3%) stddev-sans-first:1179.24 16:04:52 INFO - PROCESS | 7330 | Values: 283.7 287.5 292.7 293.2 300.3 4687.3 4791.4 4821.0 4840.0 4867.2 4764.5 4775.9 4778.4 4791.5 4803.7 3567.8 3616.3 3698.5 3769.4 3804.6 3762.6 3769.0 3770.2 3772.2 3779.5 3733.5 3735.1 3735.1 3737.3 3737.6 3559.6 3606.0 3687.3 3726.8 3734.9 3297.4 3307.1 3313.5 3316.2 3325.8 4642.9 4646.0 4646.7 4661.5 4683.5 4158.8 4268.6 4273.1 4275.7 4278.2 4958.8 4961.0 4969.5 4978.0 4987.7 3609.3 3612.2 3614.5 3617.0 3620.2 5167.8 5171.5 5187.5 5198.2 5213.6 3588.8 3667.3 3688.9 3697.5 3702.3 3739.2 3745.7 3747.8 3748.6 3754.1 4254.9 4256.4 4256.6 4256.9 4259.4 4781.2 4954.9 4973.4 4984.2 4990.7 1279.1 1292.3 1296.4 1303.8 1308.4 801.2 833.5 837.3 839.3 844.1 4256.9 4409.3 4480.7 4513.0 4519.8 5187.1 5190.9 5197.6 5205.0 5208.3 4444.9 4502.9 4503.0 4512.7 4521.5 2579.0 2722.7 2724.3 2728.8 2731.7 4865.0 4876.9 4891.7 4910.3 4911.9 5146.6 5281.2 5282.8 5285.0 5310.7 4892.5 4903.9 4907.6 4926.3 4942.6 5183.9 5187.0 5191.8 5196.4 5197.9 4973.3 5114.1 5115.6 5116.4 5119.6 4881.4 4888.6 4897.3 4917.5 4918.5 3743.9 3758.8 3763.3 3794.8 3797.2 4918.9 5147.9 5219.5 5221.7 5221.8 4984.6 4991.4 5028.4 5033.1 5045.9 5030.3 5030.6 5037.7 5051.7 5066.0 4808.1 4858.0 4908.0 4975.2 4985.8 4835.0 4837.1 5002.4 5005.4 5058.6 4921.5 4939.9 4943.9 4952.5 4959.9 16:04:52 INFO - PROCESS | 7330 | 16:04:52 INFO - PROCESS | 7330 | [#5] yui.html Cycles:180 Average:17830.22 Median:567.54 stddev:26313.30 (4636.3%) stddev-sans-first:26355.32 16:04:52 INFO - PROCESS | 7330 | Values: 605.1 627.1 638.3 674.8 715.1 12567.6 14515.8 14590.7 14609.7 14784.5 13788.0 13875.4 13919.6 13996.9 14007.5 633.0 763.7 782.6 785.8 786.0 745.8 771.0 772.4 779.7 780.5 827.1 828.6 829.0 829.4 830.3 648.5 821.3 822.5 822.6 823.5 582.4 594.6 595.1 595.5 595.6 13155.9 13179.9 13180.3 13209.0 13241.3 12301.4 12584.7 12851.3 13497.1 13688.5 11987.3 12017.2 12180.3 12337.6 12534.6 4040.8 4103.0 4267.2 4277.1 4279.9 64699.7 65221.0 65257.0 65447.0 66232.7 10613.1 10906.8 10982.8 10993.3 10998.5 19173.9 19240.1 19328.3 19411.3 19426.1 6806.3 6855.4 7016.2 7080.3 7232.3 3595.4 3625.7 3773.8 3777.4 3879.7 2706.2 2825.5 2825.7 2828.7 2831.4 538.2 555.3 564.6 566.4 570.9 495.1 497.7 557.2 568.7 604.7 7738.8 7866.3 7868.0 7951.6 8071.2 526.8 527.3 544.6 600.4 606.2 3503.4 3674.2 3684.9 3885.7 3934.5 99917.0 104873.5 107210.5 107288.5 108527.0 54046.7 73240.0 89226.0 90128.5 90956.5 61678.0 74759.6 79601.6 79606.0 81157.6 7023.3 7156.8 7229.4 7348.8 7503.0 33162.8 33513.8 42913.6 44389.0 47146.3 61219.0 68630.0 68638.0 68823.3 71955.0 9662.7 9866.9 9872.8 10060.9 10204.8 10393.1 10419.7 11104.2 11203.3 11352.4 9841.9 10055.7 10075.8 10391.7 10602.5 9500.1 9536.9 9604.0 9927.5 10188.2 9773.8 10215.4 10401.2 10415.4 10439.4 8664.5 8852.9 8954.5 9154.4 9327.5 8106.2 8494.7 8603.0 8810.9 8920.2 16:04:52 INFO - PROCESS | 7330 | -------- Summary: end -------- 16:04:52 INFO - PROCESS | 7330 | 16:04:53 INFO - TEST-INFO | 7330: exit 0 16:04:53 INFO - TEST-OK | dromaeo_css | took 1120848ms 16:04:53 INFO - TEST-START | kraken 16:04:53 INFO - operating with platform_type : linux_ 16:04:53 INFO - Initialising browser for kraken test... 16:04:53 INFO - TEST-INFO | started process 7631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKijW53/profile http://localhost:44087/getInfo.html) 16:04:58 INFO - PROCESS | 7631 | __metrics Screen width/height:1600/1200 16:04:58 INFO - PROCESS | 7631 | colorDepth:24 16:04:58 INFO - PROCESS | 7631 | Browser inner width/height: 1024/697 16:04:58 INFO - PROCESS | 7631 | __metrics 16:04:58 INFO - PROCESS | 7631 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:04:58 INFO - PROCESS | 7631 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:04:59 INFO - PROCESS | 7631 | console.error: PushService: 16:04:59 INFO - PROCESS | 7631 | stateChangeProcessEnqueue: Error transitioning state 16:04:59 INFO - PROCESS | 7631 | UnknownError 16:05:00 INFO - TEST-INFO | 7631: exit 0 16:05:00 INFO - Browser initialized. 16:05:00 INFO - Running cycle 1/1 for kraken test... 16:05:00 INFO - TEST-INFO | started process 7712 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKijW53/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:12 INFO - PROCESS | 7712 | RSS: Main: 156954624 16:05:12 INFO - PROCESS | 7712 | 16:05:14 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44087/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:15 INFO - PROCESS | 7712 | RSS: Main: 205045760 16:05:15 INFO - PROCESS | 7712 | 16:05:18 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44087/tests/kraken/driver.html?testName=audio-dft) 16:05:19 INFO - PROCESS | 7712 | RSS: Main: 195776512 16:05:19 INFO - PROCESS | 7712 | 16:05:22 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44087/tests/kraken/driver.html?testName=audio-fft) 16:05:22 INFO - PROCESS | 7712 | RSS: Main: 277667840 16:05:22 INFO - PROCESS | 7712 | 16:05:25 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44087/tests/kraken/driver.html?testName=audio-oscillator) 16:05:25 INFO - PROCESS | 7712 | RSS: Main: 187588608 16:05:25 INFO - PROCESS | 7712 | 16:05:27 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44087/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:05:28 INFO - PROCESS | 7712 | RSS: Main: 191823872 16:05:28 INFO - PROCESS | 7712 | 16:05:31 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44087/tests/kraken/driver.html?testName=imaging-darkroom) 16:05:32 INFO - PROCESS | 7712 | RSS: Main: 228655104 16:05:32 INFO - PROCESS | 7712 | 16:05:35 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44087/tests/kraken/driver.html?testName=imaging-desaturate) 16:05:36 INFO - PROCESS | 7712 | RSS: Main: 227315712 16:05:36 INFO - PROCESS | 7712 | 16:05:39 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44087/tests/kraken/driver.html?testName=json-parse-financial) 16:05:39 INFO - PROCESS | 7712 | RSS: Main: 227557376 16:05:39 INFO - PROCESS | 7712 | 16:05:41 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44087/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:05:42 INFO - PROCESS | 7712 | RSS: Main: 189730816 16:05:42 INFO - PROCESS | 7712 | 16:05:44 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:05:44 INFO - PROCESS | 7712 | RSS: Main: 196964352 16:05:44 INFO - PROCESS | 7712 | 16:05:46 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:05:47 INFO - PROCESS | 7712 | RSS: Main: 194494464 16:05:47 INFO - PROCESS | 7712 | 16:05:50 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:51 INFO - PROCESS | 7712 | RSS: Main: 189087744 16:05:51 INFO - PROCESS | 7712 | 16:05:53 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:54 INFO - PROCESS | 7712 | RSS: Main: 192516096 16:05:54 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | Cycle 1(1): loaded http://localhost:44087/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44087/tests/kraken/driver.html?testName=ai-astar) 16:05:56 INFO - PROCESS | 7712 | RSS: Main: 243122176 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | __start_tp_report 16:05:56 INFO - PROCESS | 7712 | _x_x_mozilla_page_load 16:05:56 INFO - PROCESS | 7712 | _x_x_mozilla_page_load_details 16:05:56 INFO - PROCESS | 7712 | |i|pagename|runs| 16:05:56 INFO - PROCESS | 7712 | |0;ai-astar;106;114;112;115;111;113;113;113;112;112 16:05:56 INFO - PROCESS | 7712 | |1;audio-beat-detection;159;121;125;179;122;125;150;125;186;120 16:05:56 INFO - PROCESS | 7712 | |2;audio-dft;159;162;160;160;162;160;160;161;161;161 16:05:56 INFO - PROCESS | 7712 | |3;audio-fft;76;76;75;112;76;77;75;76;129;76 16:05:56 INFO - PROCESS | 7712 | |4;audio-oscillator;86;87;136;86;88;87;131;87;85;130 16:05:56 INFO - PROCESS | 7712 | |5;imaging-gaussian-blur;117;117;116;116;116;116;117;117;116;117 16:05:56 INFO - PROCESS | 7712 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;146;146 16:05:56 INFO - PROCESS | 7712 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;95;96 16:05:56 INFO - PROCESS | 7712 | |8;json-parse-financial;66;63;67;65;65;68;65;66;66;65 16:05:56 INFO - PROCESS | 7712 | |9;json-stringify-tinderbox;67;66;66;66;66;65;67;66;67;66 16:05:56 INFO - PROCESS | 7712 | |10;stanford-crypto-aes;85;86;83;83;82;82;82;82;82;82 16:05:56 INFO - PROCESS | 7712 | |11;stanford-crypto-ccm;133;144;131;139;131;138;131;132;157;134 16:05:56 INFO - PROCESS | 7712 | |12;stanford-crypto-pbkdf2;171;175;177;173;172;173;170;175;171;174 16:05:56 INFO - PROCESS | 7712 | |13;stanford-crypto-sha256-iterative;68;73;72;68;71;68;67;68;67;67 16:05:56 INFO - PROCESS | 7712 | __end_tp_report 16:05:56 INFO - PROCESS | 7712 | __start_cc_report 16:05:56 INFO - PROCESS | 7712 | _x_x_mozilla_cycle_collect,1940 16:05:56 INFO - PROCESS | 7712 | __end_cc_report 16:05:56 INFO - PROCESS | 7712 | __startTimestamp1471388756578__endTimestamp 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | ------- Summary: start ------- 16:05:56 INFO - PROCESS | 7712 | Number of tests: 14 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.42 (2.1%) stddev-sans-first:1.20 16:05:56 INFO - PROCESS | 7712 | Values: 106.0 114.0 112.0 115.0 111.0 113.0 113.0 113.0 112.0 112.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:137.50 stddev:25.48 (18.5%) stddev-sans-first:26.20 16:05:56 INFO - PROCESS | 7712 | Values: 159.0 121.0 125.0 179.0 122.0 125.0 150.0 125.0 186.0 120.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 16:05:56 INFO - PROCESS | 7712 | Values: 159.0 162.0 160.0 160.0 162.0 160.0 160.0 161.0 161.0 161.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#3] audio-fft Cycles:10 Average:84.80 Median:76.00 stddev:19.25 (25.3%) stddev-sans-first:20.15 16:05:56 INFO - PROCESS | 7712 | Values: 76.0 76.0 75.0 112.0 76.0 77.0 75.0 76.0 129.0 76.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#4] audio-oscillator Cycles:10 Average:100.30 Median:86.50 stddev:22.17 (25.6%) stddev-sans-first:22.90 16:05:56 INFO - PROCESS | 7712 | Values: 86.0 87.0 136.0 86.0 88.0 87.0 131.0 87.0 85.0 130.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:05:56 INFO - PROCESS | 7712 | Values: 117.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:05:56 INFO - PROCESS | 7712 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 16:05:56 INFO - PROCESS | 7712 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:66.00 stddev:1.35 (2.0%) stddev-sans-first:1.42 16:05:56 INFO - PROCESS | 7712 | Values: 66.0 63.0 67.0 65.0 65.0 68.0 65.0 66.0 66.0 65.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 16:05:56 INFO - PROCESS | 7712 | Values: 67.0 66.0 66.0 66.0 66.0 65.0 67.0 66.0 67.0 66.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:82.50 stddev:1.45 (1.8%) stddev-sans-first:1.32 16:05:56 INFO - PROCESS | 7712 | Values: 85.0 86.0 83.0 83.0 82.0 82.0 82.0 82.0 82.0 82.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:136.00 stddev:8.25 (6.1%) stddev-sans-first:8.62 16:05:56 INFO - PROCESS | 7712 | Values: 133.0 144.0 131.0 139.0 131.0 138.0 131.0 132.0 157.0 134.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:173.50 stddev:2.18 (1.3%) stddev-sans-first:2.18 16:05:56 INFO - PROCESS | 7712 | Values: 171.0 175.0 177.0 173.0 172.0 173.0 170.0 175.0 171.0 174.0 16:05:56 INFO - PROCESS | 7712 | 16:05:56 INFO - PROCESS | 7712 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.00 stddev:2.23 (3.3%) stddev-sans-first:2.35 16:05:56 INFO - PROCESS | 7712 | Values: 68.0 73.0 72.0 68.0 71.0 68.0 67.0 68.0 67.0 67.0 16:05:56 INFO - PROCESS | 7712 | -------- Summary: end -------- 16:05:56 INFO - PROCESS | 7712 | 16:05:57 INFO - TEST-INFO | 7712: exit 0 16:05:57 INFO - TEST-OK | kraken | took 64083ms 16:05:57 INFO - SUITE-END | took 1184s 16:05:57 INFO - Completed test suite (00:19:44) 16:05:57 INFO - javascript benchmark 16:05:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2657.8006649501285, 2687.5968604709306, 2702.986485067575, 2716.212298433454, 2720.415110751188, 4592.72443653381, 4639.0, 4738.21819399799, 4791.161546729321, 4798.304245884345, 4690.90618187636, 4697.624190064796, 4764.928526072112, 4779.498152693969, 4787.760611969405, 4301.784910754465, 4347.956520434803, 4386.71486353386, 4432.601065904068, 4526.886827829314, 4421.115776844628, 4463.129689710508, 4480.350349199364, 4504.099180163964, 4505.189065968125, 4287.764172970486, 4335.0, 4339.305711086243, 4415.580519850641, 4419.513853476147, 4326.199653064162, 4341.826326946918, 4351.6518678505645, 4410.691251612357, 4417.624501917308, 4305.20353734557, 4342.934855977162, 4350.325699516548, 4351.456067991501, 4393.758343291118, 4590.495045544987, 4744.691595046311, 4774.689645173053, 4804.159272127398, 4813.085513752376, 4959.578435832958, 5031.47169547196, 5035.3454050973505, 5108.923366149511, 5111.4121875984365, 5080.59355251579, 5102.056119617882, 5130.948690513084, 5142.151544995071, 5151.227315902607, 4649.511801260886, 4714.222153344728, 4792.2811578262845, 4797.616190704735, 4799.0401919616215, 5186.507281808223, 5239.73801309933, 5268.315119034501, 5287.048331300402, 5293.126634105406, 5023.949760502422, 5027.044861476308, 5061.9876024795185, 5065.138926382524, 5065.645404821701, 4989.401271847401, 5053.393592768891, 5101.081805275012, 5116.437191908887, 5157.742112894341, 5007.574356179693, 5049.924251136236, 5062.088824011639, 5104.0, 5109.335786347751, 5022.799088036438, 5037.143685573461, 5044.092063428545, 5048.798048078036, 5089.033083714083, 4140.068484590943, 4173.791310434466, 4260.893477663083, 4269.082147338338, 4310.504292006397, 3703.870364537282, 3724.273766615484, 3734.5705243896755, 3765.4540091686554, 3788.9431658525145, 4472.44094488189, 4474.485434175046, 4476.776161191928, 4505.504394516601, 4520.841770538082, 5179.559737422362, 5181.766820493011, 5185.351831021122, 5195.0, 5236.502532259429, 4331.523532411432, 4418.4918734345965, 4459.397981272552, 4473.642108631302, 4539.16025535277, 4593.425821772278, 4624.95375046252, 4625.814967401334, 4626.74552899587, 4671.43942726875, 4841.273808928689, 4862.975685121552, 4873.439554451284, 4876.756162191948, 4917.041176970456, 4976.452590215146, 4994.550490455731, 5015.924761128513, 5039.647224694236, 5125.128728116155, 4748.240281554935, 4770.451398089264, 4777.0, 4779.378680771478, 4824.0, 4978.2034874419915, 4983.426905905867, 4984.127777638972, 4985.376827896513, 5028.321176641107, 4956.504349565014, 4977.203647416396, 5021.372328458942, 5027.84916452507, 5094.5669618082875, 4937.185364414975, 4943.876403089952, 4947.109520286239, 4954.603631709382, 4999.600031997505, 4940.580050695731, 4944.431390390151, 4944.53026962438, 4952.182889823139, 4991.201407774884, 4789.377380940595, 4824.228123500223, 4870.318155458168, 4873.488283730299, 4912.189488734319, 4940.7035577865445, 4972.229304457815, 4979.278004689372, 4987.850364489072, 5016.799328026838, 4873.975630121827, 4875.829345972874, 4880.194767863263, 4881.438634557071, 4934.802607895644], "value": 4676.404727841163, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.5464146720423, 558.3076984539173, 572.5762935427784, 574.9396313387095, 579.0041129257678, 28797.280067998312, 29328.26679333018, 41664.37503437443, 44070.33894491585, 44137.33793993093, 40147.59852401475, 40551.594484055226, 40554.0, 40743.59256407435, 40893.79553102238, 7601.619919004055, 7739.922600774004, 7745.0, 7746.302832745052, 7748.225177482235, 7695.769126926201, 7781.105172905131, 7783.182765809594, 7783.688652453895, 7789.454738168331, 7765.534067955912, 7818.061832580099, 7825.100113486965, 7831.29518343349, 7833.216678332178, 7713.112992005935, 7745.922540774576, 7747.573883436408, 7783.494072885301, 7796.571188584626, 7707.6916923323, 7782.0, 7789.766307010742, 7791.181925897756, 7791.22087791222, 38458.615413845786, 38747.0, 38833.417498737545, 38898.0, 38900.80549597262, 3063.402636485886, 3074.984625076883, 3078.722914937644, 3331.0, 3369.949450758241, 29194.70805291941, 29652.0, 30159.396812063856, 30219.848900755576, 30759.69240307613, 3333.3166700826273, 3556.8043757593323, 3564.803935783531, 3614.4036234021355, 3615.8011309378235, 204229.0, 257296.0, 258052.0, 259182.70408648025, 259573.0, 33214.16964575905, 34336.82831585826, 54779.452205477435, 55157.0, 55237.0, 33475.49786753201, 33806.830965845504, 33809.492857607154, 33816.32367352639, 33829.49255761166, 10722.855428914248, 10765.78468430627, 10767.515461804238, 10779.922007799158, 10782.78434431307, 8021.157778433298, 8022.2779949803635, 8028.558429286329, 8031.196880311922, 8083.959580202061, 7801.7907224380315, 7866.134725180225, 7875.251851074253, 7932.127465978738, 7957.323627491728, 3008.578798968146, 3014.0, 3017.8038427502142, 3033.8634761435787, 3124.3282694220397, 870.6604424274537, 892.4868200784445, 913.1918252346721, 972.8783902012249, 987.6592575561419, 27305.0, 27560.310992224953, 27736.583951240747, 28118.85940570325, 28618.570721439195, 924.4822899176482, 964.5996911281879, 965.7151140413595, 994.9005099490138, 1024.9795004100026, 14691.191984440744, 14704.779428308584, 14826.258687065603, 15076.0, 15125.621859453382, 385543.0, 386737.0, 387377.0, 388323.0, 388781.0, 49670.75164624154, 50144.49855501471, 50245.74877125591, 50308.49691503038, 50424.0, 1064.8189807732704, 1118.798616249083, 1179.8997783944592, 1184.810430331143, 1186.1874615888144, 27362.72637273602, 28475.0, 29369.07035100468, 29648.258793530335, 29742.0, 41486.58513414948, 42011.579884200764, 42085.789571053174, 42108.0, 42165.57834421739, 290566.0, 291361.0, 291794.0, 291932.0, 292078.0, 22941.770582293964, 24605.87697061503, 25476.108336208144, 25478.0, 25587.744122558535, 40806.0, 41135.0, 41301.1739765197, 41371.379429307985, 42185.0, 35585.64414355823, 35609.82195089008, 35642.40615486143, 35766.28467430585, 36365.81817090898, 35072.29855402929, 35408.64591354054, 35579.46630800592, 35589.46615800713, 35902.461463078595, 34396.65603343934, 34861.30277394489, 35128.47307290341, 35422.645773542965, 36073.45889811603, 30764.692353077076, 32456.675433245364, 33122.83438582792, 33168.0, 33403.498947516295, 24341.51316973615, 24925.252242432754, 25105.74894251034, 25223.62164567496, 25617.87191064107], "value": 16113.920280695198, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.4007337343082, 360.2093404976075, 361.8064335580465, 362.8856910073327, 374.1152175105874, 15902.443414480496, 17114.144292785357, 17355.045472499023, 17462.301507939697, 17467.737983930252, 15781.684366312667, 16500.66998660026, 16526.586835329123, 16975.83024169761, 17068.914655426706, 4730.550597693222, 4948.925766113512, 4958.355413796201, 4964.280179373988, 4967.0810899983435, 3633.073566240618, 3826.4642949987024, 3827.3684842001035, 3872.632099950514, 4910.361652985107, 3906.9609303907023, 5840.561957853157, 5853.473187413132, 5937.85155371117, 5946.048632218846, 5774.162746401748, 5800.216970708942, 5918.644881307114, 5931.228940237763, 5934.762609495615, 5191.169412893919, 5207.791688332462, 5208.062548741225, 5208.427073021964, 5214.217867319894, 20073.29743458972, 20111.79888201114, 20509.2821751238, 20599.691004634944, 20799.16803327865, 1875.9155837987323, 1917.587718640499, 1949.191085699433, 1968.3996381103757, 2520.7479252074827, 12649.557265495663, 13028.934855325757, 13384.263865487394, 13472.326383680776, 13909.721805563933, 1694.3561446650317, 1765.9028753418556, 1809.1677828199045, 1816.0919540229886, 1851.8240767127102, 60405.0, 70293.29706702918, 70723.29276707218, 72105.0, 73067.63466182741, 10072.244581656405, 10086.546105425274, 10094.899051009543, 10102.949485252526, 10343.327683700536, 13701.794473082911, 13741.519046833513, 13747.106438081493, 13760.587182384545, 13767.173969561858, 6709.094272273278, 6846.863062738717, 6958.512904096766, 6970.965145174242, 6980.616066116412, 6309.274433440121, 6316.084167795644, 6323.20959880015, 6448.904525827629, 6487.837804054936, 4356.49900261474, 4358.542353052947, 4377.956220437755, 4399.780010999437, 4464.866054018385, 1835.8347748702413, 1863.4782260966952, 1864.792556802298, 1886.76415448069, 1908.1318000309825, 775.7944144801633, 811.9512829230265, 859.750672305037, 861.4185424838209, 897.5512243878061, 16054.518364449084, 16326.0, 17158.48524544266, 17264.913675431544, 17360.218790154475, 811.123986095016, 814.4054839966739, 827.5655280977414, 836.3977935886152, 846.7205822078699, 8815.559222038872, 9162.175404213653, 9605.519724013771, 9620.182284505896, 9622.13400793918, 382822.0, 383056.0, 383123.0, 387973.0, 392482.0, 74100.62949685325, 75917.0, 76379.0, 77365.6131719349, 77919.0, 877.9429337093069, 906.7370462565782, 934.8270569944582, 936.5879013234292, 937.3438592984937, 15278.555431130966, 15281.6179595511, 15998.68002639964, 16128.354865805237, 16214.10822404779, 36836.26327473382, 37075.0, 38062.23875522529, 38117.80941095277, 38222.42666360062, 52180.21729673982, 52208.21687674764, 52252.73873630608, 54265.72867135639, 55015.0, 7340.449466289942, 7533.359664428366, 7570.507916985378, 7592.392608591189, 7639.388848892187, 10164.052424796726, 10235.692929212135, 10524.473776311306, 10572.471376431302, 11427.371494567546, 9202.677906273248, 9620.326577139813, 9753.951230243803, 9881.407115573089, 10087.89912100899, 9239.0, 9303.581338839633, 9713.0, 9793.069658382443, 10048.648297309852, 9680.0, 9690.951545242511, 9989.700308990741, 10181.694549163536, 10546.314489558461, 6455.193100862392, 6530.771423000362, 6586.50601204902, 6978.09284792994, 7207.171175314906, 7781.727639532589, 9472.57373418184, 9483.48687597801, 9893.307468477138, 10016.949915250378], "value": 8105.763908924053, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1213.460010295419, 1306.2619619914747, 1355.5526676196853, 1393.5749596373103, 1641.7619445180442, 6611.966940165298, 7407.296306850847, 7544.962275188624, 7546.69813207471, 7842.058952925657, 7576.015118034662, 7608.657610407531, 7623.0, 7630.122535908372, 7640.96179519103, 2171.9565608687817, 2190.1567896359857, 2190.4304880731047, 2190.9561808763815, 2193.605151072806, 2116.0, 2165.8917054147228, 2207.8896055197174, 2211.8009379155874, 2213.1258153029544, 2158.805707486326, 2176.564687062586, 2179.3570896585547, 2193.4187440328246, 2203.8787866667326, 2116.2593092417683, 2123.7239158909397, 2131.2221039320616, 2142.882141482218, 2146.066461089431, 2079.116375540389, 2117.4812171018043, 2117.8623389479635, 2119.5125121222204, 2120.724305840231, 7602.619869006583, 7684.154742978268, 7691.461597688164, 7711.884321735179, 7719.0, 4341.891452713708, 4500.5274446183, 4510.977445112786, 4545.886352841206, 4568.383268258809, 2702.4189799193073, 2811.578263260507, 2816.0, 2824.09628918744, 2828.08087371603, 953.5708930981017, 965.290511474066, 968.4818622037176, 973.7078876337141, 978.1538968792015, 10362.171026317876, 10450.634227801991, 10474.79050418995, 10481.737956551071, 10491.213159013028, 3962.7424217425773, 4126.66986641068, 4159.771212583277, 4287.592678695519, 4302.419173411612, 8130.390220733469, 8297.792555186168, 8307.37694672902, 8312.958435207785, 8321.833563328699, 4425.845095421645, 4432.559418189101, 4434.024514606781, 4436.578525040116, 4437.822487100544, 3601.1897323102617, 3709.9072523187137, 4079.1229885574744, 4081.142959978438, 4103.9179216415505, 3289.259916518812, 3298.1589694627714, 3380.087376408356, 3384.7799893006877, 3396.4226081566194, 1024.6044469227081, 1034.813733527957, 1043.5982146873357, 1044.0916402729522, 1054.3146954479496, 661.5501459007825, 761.9161892191854, 770.603139383218, 790.4782843323379, 799.564237490569, 4659.6505262105475, 4953.653244272867, 4974.527419895104, 5097.337346144977, 5123.487651234921, 800.4036992440668, 818.8526065308301, 824.6536454689045, 832.367400775403, 872.3152325424511, 3283.7701360904985, 3399.2521645238007, 3405.4721518164724, 3445.155936795451, 3517.2086280586664, 9098.818023639491, 9693.466859322805, 9701.17540009093, 9710.514474276399, 9722.951385243028, 9423.434593924387, 9604.375715578464, 9612.855807162898, 9617.471039092778, 9667.70996870095, 7123.358897699232, 7272.163701174432, 7277.781666550012, 7299.4160467163565, 7311.561306321638, 4927.334809800631, 4944.604431645388, 4957.0, 4985.8005679772405, 5169.4830516949505, 8910.420822646507, 8957.910420895707, 8959.641614335354, 8967.0, 8970.192682658331, 7934.087579928382, 8818.735437936872, 8953.552322383724, 8956.68651597191, 9062.04848490899, 6082.60463069899, 6282.968585157045, 6328.398802113792, 6419.550631455753, 6428.582142160744, 7386.15059268191, 7405.9629701851145, 7434.0, 7443.069616297963, 7467.141278752796, 6495.512836537373, 6583.506237032146, 6641.169853768279, 6715.664216789239, 6737.124173857367, 6306.842328941813, 6340.9365906340345, 6342.048692696132, 6460.12788273577, 6473.935260647333, 6305.96847015762, 6342.492600592034, 6358.682065896779, 6403.423691867754, 6477.708503117276, 4178.456800615901, 4367.781610919505, 4408.559144085566, 4498.347739577677, 4629.953700462952, 4298.075913678574, 4341.913161736811, 4413.073254616438, 4479.3057076153245, 4616.446026476844], "value": 4008.9306767769126, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.70069576596694, 287.54424237583424, 292.6561290483681, 293.186407718581, 300.3062924644071, 4687.250039993606, 4791.401074865633, 4820.999999999995, 4840.0, 4867.172580661288, 4764.499727528611, 4775.856724298268, 4778.378810754597, 4791.496892826255, 4803.663743537953, 3567.7680950738118, 3616.27674465108, 3698.5191925049708, 3769.4157405602177, 3804.619538046201, 3762.604926482721, 3768.9811550942345, 3770.1705624762644, 3772.1512659651635, 3779.5086638736925, 3733.4586484959664, 3735.122246272124, 3735.140917588956, 3737.3272810894027, 3737.6075512071247, 3559.626239244894, 3605.981970090159, 3687.3362794696945, 3726.7950262735403, 3734.906627334311, 3297.4229509835923, 3307.057488615757, 3313.4698448248405, 3316.2041110133637, 3325.833708314575, 4642.930356044662, 4645.978999135353, 4646.724059538017, 4661.510541393139, 4683.508231635663, 4158.812853421603, 4268.59448352406, 4273.059926816096, 4275.679324050709, 4278.165757677254, 4958.776855041532, 4960.975195124038, 4969.478204788481, 4978.00439912019, 4987.700737955734, 3609.3322735294055, 3612.169201083739, 3614.493970844084, 3616.9978917498674, 3620.2216523447323, 5167.8114033772445, 5171.5087066728975, 5187.507186817246, 5198.194279886624, 5213.582913366921, 3588.7618771523785, 3667.2848794485353, 3688.9077773055888, 3697.500837386918, 3702.2965636528975, 3739.2147648993473, 3745.7190710696805, 3747.775133491999, 3748.5876553579083, 3754.136548593784, 4254.914901701949, 4256.382824490432, 4256.638185754184, 4256.87229383119, 4259.424977628042, 4781.2350023996, 4954.9009019819405, 4973.353464049723, 4984.177610694195, 4990.725510096907, 1279.1110178425904, 1292.3150730113055, 1296.371259938929, 1303.8200428612122, 1308.3981368570373, 801.2228138705447, 833.4581025103631, 837.3175861672718, 839.3369238301673, 844.1305455380967, 4256.936145957813, 4409.294512877988, 4480.731156130642, 4512.977435112869, 4519.7740112994215, 5187.118189907725, 5190.92213616796, 5197.58419326453, 5204.9739751301, 5208.270842082035, 4444.911101777946, 4502.887427814266, 4502.954970450253, 4512.684112112121, 4521.535763009168, 2579.0328626764963, 2722.68689100752, 2724.305302147979, 2728.836269823817, 2731.7404846539944, 4864.95135048645, 4876.853694389174, 4891.706497610155, 4910.263460480956, 4911.926321105187, 5146.588272938156, 5281.155015197627, 5282.811367442295, 5285.0, 5310.734463276821, 4892.510748925079, 4903.901921961612, 4907.55831975124, 4926.285688575136, 4942.629302802232, 5183.948160518346, 5186.974065129651, 5191.8182863599595, 5196.376434827845, 5197.948020519898, 4973.303737476684, 5114.130597798308, 5115.565176959852, 5116.386033675983, 5119.6416250862085, 4881.389826271716, 4888.633352498506, 4897.314375987294, 4917.508249175054, 4918.458969513422, 3743.887683369503, 3758.755680880734, 3763.3414152523746, 3794.8292326845913, 3797.2025874566652, 4918.901621967612, 5147.897042059213, 5219.5041471060185, 5221.660592061504, 5221.765020574159, 4984.576311013604, 4991.401031876198, 5028.421731500778, 5033.144365457954, 5045.949540504548, 5030.346055012971, 5030.647854650139, 5037.672551284155, 5051.747412629427, 5065.974670126773, 4808.083615393009, 4857.975710121426, 4908.0, 4975.228839529878, 4985.775640096132, 4835.0, 4837.05677392905, 5002.424721157114, 5005.449400566008, 5058.64589478733, 4921.50784921505, 4939.851804445872, 4943.925841112315, 4952.529509696573, 4959.876003099807], "value": 3786.644392019981, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.1030020884527, 627.131422979174, 638.3010603389287, 674.8144260328411, 715.0811207598231, 12567.560135395262, 14515.782263266059, 14590.708185836276, 14609.707805843876, 14784.482543110993, 13788.0, 13875.444982200725, 13919.582412527641, 13996.860031399683, 14007.509737159176, 633.0472638678782, 763.7479631721519, 782.5774081995716, 785.7917651822272, 785.9528428294292, 745.8396444764375, 771.0131032278692, 772.3859531672306, 779.6530543907963, 780.4614815777096, 827.1149869639488, 828.6395417993162, 828.9502629842228, 829.357248132696, 830.2610676497922, 648.4876947211698, 821.2526600793279, 822.4777266435797, 822.5517093184199, 823.5100115431309, 582.4292193650247, 594.6491569973737, 595.0538643556722, 595.5176307191189, 595.5920194666667, 13155.86844131556, 13179.86820131796, 13180.340982950815, 13209.0, 13241.337933103307, 12301.384930753427, 12584.68538286541, 12851.293178875158, 13497.055206135574, 13688.52090176849, 11987.340696261703, 12017.158798884082, 12180.269183848908, 12337.568185113567, 12534.623961281177, 4040.8181631826637, 4103.0, 4267.167902259061, 4277.144571085795, 4279.935800962988, 64699.67650161766, 65221.0, 65257.0, 65447.0, 66232.668836656, 10613.097886679565, 10906.836397454044, 10982.780344393068, 10993.34039957589, 10998.450077496092, 19173.945433001318, 19240.134193961025, 19328.323508677127, 19411.3206037788, 19426.125824337938, 6806.285340039323, 6855.414518048433, 7016.228214896358, 7080.256573059857, 7232.276772322725, 3595.388783906742, 3625.691816195653, 3773.772480092259, 3777.433384992273, 3879.670228030649, 2706.215197592676, 2825.4631619992138, 2825.6891741908785, 2828.7029861864207, 2831.40540486496, 538.1658429434361, 555.3225065420181, 564.604776656334, 566.3656704490996, 570.9429057094258, 495.091507084504, 497.71381455662413, 557.2338035201598, 568.7213265499958, 604.7361015477672, 7738.806529836686, 7866.331361834308, 7867.960660196777, 7951.602419878983, 8071.152528984489, 526.7919171927144, 527.2565682387891, 544.5806728818787, 600.3996003996004, 606.2149516376303, 3503.386907291214, 3674.1549443628114, 3684.9078773030888, 3885.747426417274, 3934.488516492838, 99917.0, 104873.47563262135, 107210.46394767976, 107288.46355768171, 108527.0, 54046.729766350916, 73240.0, 89226.0, 90128.54935725279, 90956.54521727482, 61678.0, 74759.62620186864, 79601.60199198966, 79606.0, 81157.59421202856, 7023.297670232936, 7156.821079473055, 7229.421646268181, 7348.77953661391, 7502.962485187539, 33162.83418582891, 33513.83243083769, 42913.57086429096, 44389.0, 47146.29280560863, 61219.0, 68630.0, 68637.97043044258, 68823.31176688168, 71955.0, 9662.71011869645, 9866.851997219905, 9872.753181170241, 10060.899391005996, 10204.795904082026, 10393.116585090049, 10419.739506512398, 11104.167187460811, 11203.327800332007, 11352.375619341015, 9841.901580984098, 10055.74860628461, 10075.848862266925, 10391.740206494898, 10602.46987650599, 9500.144986951207, 9536.904630953602, 9603.951980240334, 9927.503624818586, 10188.235882308709, 9773.804523909339, 10215.438150901475, 10401.167906567607, 10415.42715150674, 10439.37363758177, 8664.523451210243, 8852.867206992029, 8954.507502087186, 9154.359194856295, 9327.486988215442, 8106.189381061847, 8494.655878533564, 8602.956985215034, 8810.911890881009, 8920.152585504367], "value": 6448.788364036787, "unit": "score"}], "name": "dromaeo_css", "value": 6253.443793446927, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 112.0, 115.0, 111.0, 113.0, 113.0, 113.0, 112.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 121.0, 125.0, 179.0, 122.0, 125.0, 150.0, 125.0, 186.0, 120.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 160.0, 160.0, 162.0, 160.0, 160.0, 161.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 112.0, 76.0, 77.0, 75.0, 76.0, 129.0, 76.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 136.0, 86.0, 88.0, 87.0, 131.0, 87.0, 85.0, 130.0], "value": 100.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 67.0, 65.0, 65.0, 68.0, 65.0, 66.0, 66.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 66.0, 67.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 83.0, 82.0, 82.0, 82.0, 82.0, 82.0, 82.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 144.0, 131.0, 139.0, 131.0, 138.0, 131.0, 132.0, 157.0, 134.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 177.0, 173.0, 172.0, 173.0, 170.0, 175.0, 171.0, 174.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 72.0, 68.0, 71.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1550.2, "alertThreshold": 2.0}]} 16:05:57 INFO - Return code: 0 16:05:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:57 INFO - # TBPL SUCCESS # 16:05:57 INFO - Running post-action listener: _resource_record_post_action 16:05:57 INFO - [mozharness: 2016-08-16 23:05:57.887358Z] Finished run-tests step (success) 16:05:57 INFO - Running post-run listener: _resource_record_post_run 16:05:57 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 8192; Write bytes: 398041088; Read time: 24; Write time: 342284 16:05:57 INFO - TinderboxPrint: CPU usage
13.0% 16:05:57 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 16:05:57 INFO - TinderboxPrint: I/O write bytes / time
398,041,088 / 342,284 16:05:57 INFO - TinderboxPrint: CPU idle
8,339.6 (87.0%) 16:05:57 INFO - TinderboxPrint: CPU user
1,198.9 (12.5%) 16:05:57 INFO - TinderboxPrint: Swap in / out
0 / 0 16:05:57 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115212288; Read time: 0; Write time: 148784 16:05:57 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282214400; Read time: 12; Write time: 193500 16:05:58 INFO - Running post-run listener: _upload_blobber_files 16:05:58 INFO - Blob upload gear active. 16:05:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:05:58 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'] 16:05:58 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 16:05:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:06:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:02 INFO - (blobuploader) - INFO - Done attempting. 16:06:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:06:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:06:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:03 INFO - (blobuploader) - INFO - Done attempting. 16:06:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:06:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:06:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:04 INFO - (blobuploader) - INFO - Done attempting. 16:06:04 INFO - (blobuploader) - INFO - Iteration through files over. 16:06:04 INFO - Return code: 0 16:06:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:06:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:06:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dede0f9a0c4aba28abad9c0495ec68d76ac7417cd6b4a13941ba3b359d4a3149cbc9dfb7d82ad9876688e98fbbbad96436c016d3acd25abe7b21c4ccd3ecde62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/83323f2aa194c957b128845b6da0c53cf488b48093bf560f9e17e63016ffae32461fa2d49b84d7ed56ecdcf1439274bd037a6842602339e0f3b5fc6388d9edad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b886c759a291976e0cde399ff98fa20beb707be06ed8160620b8e6d56060d6f206cd901ccf03d82f901362c7a5385ddd5ebdac014e5c849a419be88d67376d0"} 16:06:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:06:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:06:04 INFO - Contents: 16:06:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dede0f9a0c4aba28abad9c0495ec68d76ac7417cd6b4a13941ba3b359d4a3149cbc9dfb7d82ad9876688e98fbbbad96436c016d3acd25abe7b21c4ccd3ecde62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/83323f2aa194c957b128845b6da0c53cf488b48093bf560f9e17e63016ffae32461fa2d49b84d7ed56ecdcf1439274bd037a6842602339e0f3b5fc6388d9edad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b886c759a291976e0cde399ff98fa20beb707be06ed8160620b8e6d56060d6f206cd901ccf03d82f901362c7a5385ddd5ebdac014e5c849a419be88d67376d0"} 16:06:04 INFO - Running post-run listener: copy_logs_to_upload_dir 16:06:04 INFO - Copying logs to upload dir... 16:06:04 INFO - mkdir: /builds/slave/test/build/upload/logs 16:06:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.684657 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-16 16:06:04.676061) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 16:06:04.680347) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008 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/dede0f9a0c4aba28abad9c0495ec68d76ac7417cd6b4a13941ba3b359d4a3149cbc9dfb7d82ad9876688e98fbbbad96436c016d3acd25abe7b21c4ccd3ecde62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/83323f2aa194c957b128845b6da0c53cf488b48093bf560f9e17e63016ffae32461fa2d49b84d7ed56ecdcf1439274bd037a6842602339e0f3b5fc6388d9edad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b886c759a291976e0cde399ff98fa20beb707be06ed8160620b8e6d56060d6f206cd901ccf03d82f901362c7a5385ddd5ebdac014e5c849a419be88d67376d0"} build_url:https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/R_vt3wuYS7ixTdFLxGVJ5Q/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/dede0f9a0c4aba28abad9c0495ec68d76ac7417cd6b4a13941ba3b359d4a3149cbc9dfb7d82ad9876688e98fbbbad96436c016d3acd25abe7b21c4ccd3ecde62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/83323f2aa194c957b128845b6da0c53cf488b48093bf560f9e17e63016ffae32461fa2d49b84d7ed56ecdcf1439274bd037a6842602339e0f3b5fc6388d9edad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8b886c759a291976e0cde399ff98fa20beb707be06ed8160620b8e6d56060d6f206cd901ccf03d82f901362c7a5385ddd5ebdac014e5c849a419be88d67376d0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 16:06:04.716899) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:06:04.717385) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1471387516.219571-385477008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 16:06:04.799656) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 16:06:04.800149) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 16:06:04.800666) ========= ========= Total master_lag: 0.18 =========