builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-008 starttime: 1471529210.89 results: success (0) buildid: 20160817173815 builduid: ff6880557c9d4be281ebdd3bf2e45b1e revision: 30da32629a3c6ef74b8c66f819c08b1106f6da91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:50.893418) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:50.893836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:50.894138) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:50.921836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:50.922120) ========= 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=32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 07:06:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.7M=0s 2016-08-18 07:06:51 (38.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:51.033297) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:51.033578) ========= 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=32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020555 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:51.082642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 07:06:51.082941) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 30da32629a3c6ef74b8c66f819c08b1106f6da91 --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 30da32629a3c6ef74b8c66f819c08b1106f6da91 --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=32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 07:06:51,368 truncating revision to first 12 chars 2016-08-18 07:06:51,368 Setting DEBUG logging. 2016-08-18 07:06:51,368 attempt 1/10 2016-08-18 07:06:51,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/30da32629a3c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 07:06:52,690 unpacking tar archive at: autoland-30da32629a3c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.659683 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 07:06:52.756105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:52.756421) ========= script_repo_revision: 30da32629a3c6ef74b8c66f819c08b1106f6da91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:52.756809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:52.757088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 07:06:52.767895) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-18 07:06:52.768171) ========= /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=32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:06:53 INFO - MultiFileLogger online at 20160818 07:06:53 in /builds/slave/test 07:06:53 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 07:06:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:06:53 INFO - {'append_to_log': False, 07:06:53 INFO - 'base_work_dir': '/builds/slave/test', 07:06:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 07:06:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:06:53 INFO - 'branch': 'Autoland-Non-PGO', 07:06:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:06:53 INFO - 'config_files': ('talos/linux_config.py',), 07:06:53 INFO - 'default_actions': ('clobber', 07:06:53 INFO - 'read-buildbot-config', 07:06:53 INFO - 'download-and-extract', 07:06:53 INFO - 'populate-webroot', 07:06:53 INFO - 'create-virtualenv', 07:06:53 INFO - 'install', 07:06:53 INFO - 'run-tests'), 07:06:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:06:53 INFO - 'download_minidump_stackwalk': True, 07:06:53 INFO - 'download_symbols': 'ondemand', 07:06:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:06:53 INFO - 'tooltool.py': '/tools/tooltool.py', 07:06:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:06:53 INFO - '/tools/misc-python/virtualenv.py')}, 07:06:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:06:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:06:53 INFO - 'installer_path': 'installer.exe', 07:06:53 INFO - 'log_level': 'info', 07:06:53 INFO - 'log_name': 'talos', 07:06:53 INFO - 'log_to_console': True, 07:06:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:06:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:06:53 INFO - 'opt_config_files': (), 07:06:53 INFO - 'pip_index': False, 07:06:53 INFO - 'sps_profile': False, 07:06:53 INFO - 'sps_profile_interval': 0, 07:06:53 INFO - 'suite': 'dromaeojs', 07:06:53 INFO - 'system_bits': '32', 07:06:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:06:53 INFO - 'title': 'talos-linux64-ix-008', 07:06:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:06:53 INFO - 'use_talos_json': True, 07:06:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:06:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:06:53 INFO - 'work_dir': 'build'} 07:06:53 INFO - [mozharness: 2016-08-18 14:06:53.147078Z] Running clobber step. 07:06:53 INFO - Running pre-action listener: _resource_record_pre_action 07:06:53 INFO - Running main action method: clobber 07:06:53 INFO - rmtree: /builds/slave/test/build 07:06:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:06:53 INFO - Running post-action listener: _resource_record_post_action 07:06:53 INFO - [mozharness: 2016-08-18 14:06:53.616951Z] Finished clobber step (success) 07:06:53 INFO - [mozharness: 2016-08-18 14:06:53.617052Z] Running read-buildbot-config step. 07:06:53 INFO - Running pre-action listener: _resource_record_pre_action 07:06:53 INFO - Running main action method: read_buildbot_config 07:06:53 INFO - Using buildbot properties: 07:06:53 INFO - { 07:06:53 INFO - "project": "", 07:06:53 INFO - "product": "firefox", 07:06:53 INFO - "script_repo_revision": "production", 07:06:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 07:06:53 INFO - "repository": "", 07:06:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 07:06:53 INFO - "buildid": "20160817173815", 07:06:53 INFO - "pgo_build": "False", 07:06:53 INFO - "basedir": "/builds/slave/test", 07:06:53 INFO - "buildnumber": 410, 07:06:53 INFO - "slavename": "talos-linux64-ix-008", 07:06:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:06:53 INFO - "platform": "ubuntu64_hw", 07:06:53 INFO - "scheduler": "tests-autoland-linux64-talos", 07:06:53 INFO - "branch": "autoland", 07:06:53 INFO - "repo_path": "integration/autoland", 07:06:53 INFO - "revision": "30da32629a3c6ef74b8c66f819c08b1106f6da91", 07:06:53 INFO - "stage_platform": "linux64", 07:06:53 INFO - "builduid": "ff6880557c9d4be281ebdd3bf2e45b1e", 07:06:53 INFO - "slavebuilddir": "test" 07:06:53 INFO - } 07:06:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:06:53 INFO - Running post-action listener: _resource_record_post_action 07:06:53 INFO - [mozharness: 2016-08-18 14:06:53.619504Z] Finished read-buildbot-config step (success) 07:06:53 INFO - [mozharness: 2016-08-18 14:06:53.619592Z] Running download-and-extract step. 07:06:53 INFO - Running pre-action listener: _resource_record_pre_action 07:06:53 INFO - Running main action method: download_and_extract 07:06:53 INFO - mkdir: /builds/slave/test/build/tests 07:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:53 INFO - trying https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:06:53 INFO - Downloading https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:06:54 INFO - Downloaded 1361 bytes. 07:06:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:06:54 INFO - Using the following test package requirements: 07:06:54 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:06:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:06:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:06:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'jsshell-linux-x86_64.zip'], 07:06:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:06:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:06:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:06:54 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:06:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:06:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:06:54 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:06:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:06:54 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')]} 07:06:54 INFO - trying https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:06:54 INFO - Downloading https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:06:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:06:54 INFO - Downloaded 20317507 bytes. 07:06:54 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 07:06:59 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 07:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:59 INFO - trying https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:06:59 INFO - Downloading https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:07:00 INFO - Downloaded 20317507 bytes. 07:07:00 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 07:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:04 INFO - trying https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:07:04 INFO - Downloading https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:07:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:07:05 INFO - Downloaded 14413981 bytes. 07:07:05 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 07:07:05 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')]} 07:07:05 INFO - trying https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:05 INFO - Downloading https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:07:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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 07:07:06 INFO - Downloaded 55771793 bytes. 07:07:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:06 INFO - mkdir: /builds/slave/test/properties 07:07:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:07:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:07:06 INFO - Contents: 07:07:06 INFO - build_url:https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:07 INFO - Running post-action listener: _resource_record_post_action 07:07:07 INFO - Running post-action listener: set_extra_try_arguments 07:07:07 INFO - [mozharness: 2016-08-18 14:07:07.644622Z] Finished download-and-extract step (success) 07:07:07 INFO - [mozharness: 2016-08-18 14:07:07.644848Z] Running populate-webroot step. 07:07:07 INFO - Running pre-action listener: _resource_record_pre_action 07:07:07 INFO - Running main action method: populate_webroot 07:07:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:07:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:07:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:07:07 INFO - '--tppagecycles', 07:07:07 INFO - '7'], 07:07:07 INFO - 'tests': ['tsvgm']}}, 07:07:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:07:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:07:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:07:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:07:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['damp', 'tps']}, 07:07:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'tests': ['damp', 'tps']}, 07:07:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['dromaeo_dom']}, 07:07:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:07:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['basic_compositor_video']}, 07:07:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:07:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['a11yr', 07:07:07 INFO - 'ts_paint', 07:07:07 INFO - 'tpaint', 07:07:07 INFO - 'sessionrestore', 07:07:07 INFO - 'sessionrestore_no_auto_restore', 07:07:07 INFO - 'tabpaint']}, 07:07:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:07:07 INFO - 'ts_paint', 07:07:07 INFO - 'tpaint', 07:07:07 INFO - 'sessionrestore', 07:07:07 INFO - 'sessionrestore_no_auto_restore', 07:07:07 INFO - 'tabpaint']}, 07:07:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:07:07 INFO - 'ts_paint', 07:07:07 INFO - 'tpaint', 07:07:07 INFO - 'sessionrestore', 07:07:07 INFO - 'sessionrestore_no_auto_restore', 07:07:07 INFO - 'tabpaint']}, 07:07:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:07:07 INFO - 'ts_paint', 07:07:07 INFO - 'tpaint', 07:07:07 INFO - 'sessionrestore', 07:07:07 INFO - 'sessionrestore_no_auto_restore', 07:07:07 INFO - 'tabpaint']}, 07:07:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['a11yr', 07:07:07 INFO - 'ts_paint', 07:07:07 INFO - 'tpaint', 07:07:07 INFO - 'sessionrestore', 07:07:07 INFO - 'sessionrestore_no_auto_restore', 07:07:07 INFO - 'tabpaint']}, 07:07:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['a11yr', 07:07:07 INFO - 'ts_paint', 07:07:07 INFO - 'tpaint', 07:07:07 INFO - 'sessionrestore', 07:07:07 INFO - 'sessionrestore_no_auto_restore', 07:07:07 INFO - 'tabpaint']}, 07:07:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['tsvgx', 07:07:07 INFO - 'tsvgr_opacity', 07:07:07 INFO - 'tart', 07:07:07 INFO - 'tscrollx', 07:07:07 INFO - 'cart']}, 07:07:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:07:07 INFO - 'tsvgr_opacity', 07:07:07 INFO - 'tart', 07:07:07 INFO - 'tscrollx', 07:07:07 INFO - 'cart']}, 07:07:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'talos_options': ['--disable-e10s'], 07:07:07 INFO - 'tests': ['tp5o']}, 07:07:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'tests': ['tp5o']}, 07:07:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'talos_options': ['--disable-e10s', 07:07:07 INFO - '--xperf_path', 07:07:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:07 INFO - 'tests': ['tp5n']}, 07:07:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:07 INFO - 'talos_options': ['--xperf_path', 07:07:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:07 INFO - 'tests': ['tp5n']}}, 07:07:07 INFO - 'talos.zip': {'path': '', 07:07:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:07:07 INFO - Running post-action listener: _resource_record_post_action 07:07:07 INFO - [mozharness: 2016-08-18 14:07:07.663592Z] Finished populate-webroot step (success) 07:07:07 INFO - [mozharness: 2016-08-18 14:07:07.663681Z] Running create-virtualenv step. 07:07:07 INFO - Running pre-action listener: _resource_record_pre_action 07:07:07 INFO - Running main action method: create_virtualenv 07:07:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:07:07 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 07:07:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:07:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:07:07 INFO - Using real prefix '/usr' 07:07:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:07:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:07:10 INFO - Installing pip.................done. 07:07:10 INFO - Return code: 0 07:07:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:07:10 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')]} 07:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:10 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')]} 07:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:10 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:10 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 07:07:10 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 07:07:10 INFO - Using env: {'DISPLAY': ':0', 07:07:10 INFO - 'HOME': '/home/cltbld', 07:07:10 INFO - 'LANG': 'en_US.UTF-8', 07:07:10 INFO - 'LANGUAGE': 'en_US:en', 07:07:10 INFO - 'LOGNAME': 'cltbld', 07:07:10 INFO - 'MAIL': '/var/mail/cltbld', 07:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:10 INFO - 'MOZ_NO_REMOTE': '1', 07:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:10 INFO - 'NO_EM_RESTART': '1', 07:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:10 INFO - 'PWD': '/builds/slave/test', 07:07:10 INFO - 'SHELL': '/bin/bash', 07:07:10 INFO - 'SHLVL': '1', 07:07:10 INFO - 'TERM': 'linux', 07:07:10 INFO - 'TMOUT': '86400', 07:07:10 INFO - 'USER': 'cltbld', 07:07:10 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:10 INFO - '_': '/tools/buildbot/bin/python'} 07:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:10 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 07:07:10 INFO - Cleaning up... 07:07:10 INFO - Return code: 0 07:07:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:07:10 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')]} 07:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:10 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')]} 07:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:10 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:10 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 07:07:10 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 07:07:10 INFO - Using env: {'DISPLAY': ':0', 07:07:10 INFO - 'HOME': '/home/cltbld', 07:07:10 INFO - 'LANG': 'en_US.UTF-8', 07:07:10 INFO - 'LANGUAGE': 'en_US:en', 07:07:10 INFO - 'LOGNAME': 'cltbld', 07:07:10 INFO - 'MAIL': '/var/mail/cltbld', 07:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:10 INFO - 'MOZ_NO_REMOTE': '1', 07:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:10 INFO - 'NO_EM_RESTART': '1', 07:07:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:10 INFO - 'PWD': '/builds/slave/test', 07:07:10 INFO - 'SHELL': '/bin/bash', 07:07:10 INFO - 'SHLVL': '1', 07:07:10 INFO - 'TERM': 'linux', 07:07:10 INFO - 'TMOUT': '86400', 07:07:10 INFO - 'USER': 'cltbld', 07:07:10 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:10 INFO - '_': '/tools/buildbot/bin/python'} 07:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:10 INFO - Downloading/unpacking psutil>=3.1.1 07:07:10 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 07:07:10 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 07:07:10 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 07:07:10 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 07:07:10 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 07:07:10 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 07:07:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:07:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:12 INFO - Installing collected packages: psutil 07:07:12 INFO - Running setup.py install for psutil 07:07:12 INFO - building 'psutil._psutil_linux' extension 07:07:12 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 07:07:12 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 07:07:12 INFO - building 'psutil._psutil_posix' extension 07:07:12 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 07:07:13 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 07:07:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:13 INFO - Successfully installed psutil 07:07:13 INFO - Cleaning up... 07:07:13 INFO - Return code: 0 07:07:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:13 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:13 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 07:07:13 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 07:07:13 INFO - Using env: {'DISPLAY': ':0', 07:07:13 INFO - 'HOME': '/home/cltbld', 07:07:13 INFO - 'LANG': 'en_US.UTF-8', 07:07:13 INFO - 'LANGUAGE': 'en_US:en', 07:07:13 INFO - 'LOGNAME': 'cltbld', 07:07:13 INFO - 'MAIL': '/var/mail/cltbld', 07:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:13 INFO - 'MOZ_NO_REMOTE': '1', 07:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:13 INFO - 'NO_EM_RESTART': '1', 07:07:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:13 INFO - 'PWD': '/builds/slave/test', 07:07:13 INFO - 'SHELL': '/bin/bash', 07:07:13 INFO - 'SHLVL': '1', 07:07:13 INFO - 'TERM': 'linux', 07:07:13 INFO - 'TMOUT': '86400', 07:07:13 INFO - 'USER': 'cltbld', 07:07:13 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:13 INFO - '_': '/tools/buildbot/bin/python'} 07:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:07:13 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 07:07:13 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 07:07:13 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 07:07:13 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 07:07:13 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 07:07:13 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 07:07:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:07:15 INFO - Installing collected packages: mozsystemmonitor 07:07:15 INFO - Running setup.py install for mozsystemmonitor 07:07:15 INFO - Successfully installed mozsystemmonitor 07:07:15 INFO - Cleaning up... 07:07:15 INFO - Return code: 0 07:07:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:15 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:15 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 07:07:15 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 07:07:15 INFO - Using env: {'DISPLAY': ':0', 07:07:15 INFO - 'HOME': '/home/cltbld', 07:07:15 INFO - 'LANG': 'en_US.UTF-8', 07:07:15 INFO - 'LANGUAGE': 'en_US:en', 07:07:15 INFO - 'LOGNAME': 'cltbld', 07:07:15 INFO - 'MAIL': '/var/mail/cltbld', 07:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:15 INFO - 'MOZ_NO_REMOTE': '1', 07:07:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:15 INFO - 'NO_EM_RESTART': '1', 07:07:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:15 INFO - 'PWD': '/builds/slave/test', 07:07:15 INFO - 'SHELL': '/bin/bash', 07:07:15 INFO - 'SHLVL': '1', 07:07:15 INFO - 'TERM': 'linux', 07:07:15 INFO - 'TMOUT': '86400', 07:07:15 INFO - 'USER': 'cltbld', 07:07:15 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:15 INFO - '_': '/tools/buildbot/bin/python'} 07:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:15 INFO - Downloading/unpacking jsonschema==2.5.1 07:07:15 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 07:07:15 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 07:07:15 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 07:07:15 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 07:07:15 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 07:07:15 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 07:07:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:07:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:07:17 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 07:07:17 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 07:07:17 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 07:07:17 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 07:07:17 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 07:07:17 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 07:07:17 INFO - Downloading functools32-3.2.3-2.tar.gz 07:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:07:17 INFO - warning: no files found matching '*.txt' 07:07:17 INFO - no previously-included directories found matching 'build' 07:07:17 INFO - no previously-included directories found matching 'dist' 07:07:17 INFO - no previously-included directories found matching '.git*' 07:07:17 INFO - Installing collected packages: jsonschema, functools32 07:07:17 INFO - Running setup.py install for functools32 07:07:17 INFO - warning: no files found matching '*.txt' 07:07:17 INFO - no previously-included directories found matching 'build' 07:07:17 INFO - no previously-included directories found matching 'dist' 07:07:17 INFO - no previously-included directories found matching '.git*' 07:07:18 INFO - Successfully installed jsonschema functools32 07:07:18 INFO - Cleaning up... 07:07:18 INFO - Return code: 0 07:07:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:18 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:18 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 07:07:18 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 07:07:18 INFO - Using env: {'DISPLAY': ':0', 07:07:18 INFO - 'HOME': '/home/cltbld', 07:07:18 INFO - 'LANG': 'en_US.UTF-8', 07:07:18 INFO - 'LANGUAGE': 'en_US:en', 07:07:18 INFO - 'LOGNAME': 'cltbld', 07:07:18 INFO - 'MAIL': '/var/mail/cltbld', 07:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:18 INFO - 'MOZ_NO_REMOTE': '1', 07:07:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:18 INFO - 'NO_EM_RESTART': '1', 07:07:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:18 INFO - 'PWD': '/builds/slave/test', 07:07:18 INFO - 'SHELL': '/bin/bash', 07:07:18 INFO - 'SHLVL': '1', 07:07:18 INFO - 'TERM': 'linux', 07:07:18 INFO - 'TMOUT': '86400', 07:07:18 INFO - 'USER': 'cltbld', 07:07:18 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:18 INFO - '_': '/tools/buildbot/bin/python'} 07:07:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:07:18 INFO - Cleaning up... 07:07:18 INFO - Return code: 0 07:07:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:18 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:18 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 07:07:18 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 07:07:18 INFO - Using env: {'DISPLAY': ':0', 07:07:18 INFO - 'HOME': '/home/cltbld', 07:07:18 INFO - 'LANG': 'en_US.UTF-8', 07:07:18 INFO - 'LANGUAGE': 'en_US:en', 07:07:18 INFO - 'LOGNAME': 'cltbld', 07:07:18 INFO - 'MAIL': '/var/mail/cltbld', 07:07:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:18 INFO - 'MOZ_NO_REMOTE': '1', 07:07:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:18 INFO - 'NO_EM_RESTART': '1', 07:07:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:18 INFO - 'PWD': '/builds/slave/test', 07:07:18 INFO - 'SHELL': '/bin/bash', 07:07:18 INFO - 'SHLVL': '1', 07:07:18 INFO - 'TERM': 'linux', 07:07:18 INFO - 'TMOUT': '86400', 07:07:18 INFO - 'USER': 'cltbld', 07:07:18 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:18 INFO - '_': '/tools/buildbot/bin/python'} 07:07:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:07:18 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 07:07:18 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 07:07:18 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 07:07:18 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 07:07:18 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 07:07:18 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 07:07:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:07:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:07:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 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 07:07:20 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 07:07:21 INFO - Downloading docopt-0.6.1.tar.gz 07:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:07:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:07:21 INFO - Running setup.py install for blobuploader 07:07:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:07:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:07:21 INFO - Running setup.py install for requests 07:07:21 INFO - Running setup.py install for docopt 07:07:21 INFO - Successfully installed blobuploader requests docopt 07:07:21 INFO - Cleaning up... 07:07:21 INFO - Return code: 0 07:07:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07: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')]} 07:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07: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')]} 07:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:21 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:21 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 07:07:21 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 07:07:21 INFO - Using env: {'DISPLAY': ':0', 07:07:21 INFO - 'HOME': '/home/cltbld', 07:07:21 INFO - 'LANG': 'en_US.UTF-8', 07:07:21 INFO - 'LANGUAGE': 'en_US:en', 07:07:21 INFO - 'LOGNAME': 'cltbld', 07:07:21 INFO - 'MAIL': '/var/mail/cltbld', 07:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:21 INFO - 'MOZ_NO_REMOTE': '1', 07:07:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:21 INFO - 'NO_EM_RESTART': '1', 07:07:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:21 INFO - 'PWD': '/builds/slave/test', 07:07:21 INFO - 'SHELL': '/bin/bash', 07:07:21 INFO - 'SHLVL': '1', 07:07:21 INFO - 'TERM': 'linux', 07:07:21 INFO - 'TMOUT': '86400', 07:07:21 INFO - 'USER': 'cltbld', 07:07:21 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:21 INFO - '_': '/tools/buildbot/bin/python'} 07:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:22 INFO - Running setup.py (path:/tmp/pip-S7QIdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:22 INFO - Running setup.py (path:/tmp/pip-LjF3rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:22 INFO - Running setup.py (path:/tmp/pip-9mBkon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:22 INFO - Running setup.py (path:/tmp/pip-rhZFPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:22 INFO - Running setup.py (path:/tmp/pip-1GY7Tx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:22 INFO - Running setup.py (path:/tmp/pip-3PUoTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:22 INFO - Running setup.py (path:/tmp/pip-B0gGvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:22 INFO - Running setup.py (path:/tmp/pip-K8kSNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:22 INFO - Running setup.py (path:/tmp/pip-rgKh20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:22 INFO - Running setup.py (path:/tmp/pip-IftVIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:22 INFO - Running setup.py (path:/tmp/pip-TxXavu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:22 INFO - Running setup.py (path:/tmp/pip-_YayDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:22 INFO - Running setup.py (path:/tmp/pip-xnFFS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:22 INFO - Running setup.py (path:/tmp/pip-9i12vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:23 INFO - Running setup.py (path:/tmp/pip-SSrSzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:23 INFO - Running setup.py (path:/tmp/pip-wxrNB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:23 INFO - Running setup.py (path:/tmp/pip-43RbgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:07:23 INFO - Running setup.py install for manifestparser 07:07:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:07:23 INFO - Running setup.py install for mozcrash 07:07:23 INFO - Running setup.py install for mozdebug 07:07:23 INFO - Running setup.py install for mozdevice 07:07:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:07:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:07:23 INFO - Running setup.py install for mozfile 07:07:23 INFO - Running setup.py install for mozhttpd 07:07:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:07:23 INFO - Running setup.py install for mozinfo 07:07:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:07:23 INFO - Running setup.py install for mozInstall 07:07:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Running setup.py install for mozleak 07:07:24 INFO - Running setup.py install for mozlog 07:07:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Running setup.py install for moznetwork 07:07:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Running setup.py install for mozprocess 07:07:24 INFO - Running setup.py install for mozprofile 07:07:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Running setup.py install for mozrunner 07:07:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:07:24 INFO - Running setup.py install for mozscreenshot 07:07:24 INFO - Running setup.py install for moztest 07:07:24 INFO - Running setup.py install for mozversion 07:07:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:07:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:07:25 INFO - Cleaning up... 07:07:25 INFO - Return code: 0 07:07:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:25 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:25 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 07:07:25 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 07:07:25 INFO - Using env: {'DISPLAY': ':0', 07:07:25 INFO - 'HOME': '/home/cltbld', 07:07:25 INFO - 'LANG': 'en_US.UTF-8', 07:07:25 INFO - 'LANGUAGE': 'en_US:en', 07:07:25 INFO - 'LOGNAME': 'cltbld', 07:07:25 INFO - 'MAIL': '/var/mail/cltbld', 07:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:25 INFO - 'MOZ_NO_REMOTE': '1', 07:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:25 INFO - 'NO_EM_RESTART': '1', 07:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:25 INFO - 'PWD': '/builds/slave/test', 07:07:25 INFO - 'SHELL': '/bin/bash', 07:07:25 INFO - 'SHLVL': '1', 07:07:25 INFO - 'TERM': 'linux', 07:07:25 INFO - 'TMOUT': '86400', 07:07:25 INFO - 'USER': 'cltbld', 07:07:25 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:25 INFO - '_': '/tools/buildbot/bin/python'} 07:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:25 INFO - Running setup.py (path:/tmp/pip-KQy7lA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:25 INFO - Running setup.py (path:/tmp/pip-2bjgA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:25 INFO - Running setup.py (path:/tmp/pip-8zc1wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:25 INFO - Running setup.py (path:/tmp/pip-9Lwrgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:25 INFO - Running setup.py (path:/tmp/pip-ijk8ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:25 INFO - Running setup.py (path:/tmp/pip-VtCkB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:25 INFO - Running setup.py (path:/tmp/pip-dkbnWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:25 INFO - Running setup.py (path:/tmp/pip-NhUW3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:25 INFO - Running setup.py (path:/tmp/pip-OT8v1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:25 INFO - Running setup.py (path:/tmp/pip-5he9OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:25 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)) 07:07:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:25 INFO - Running setup.py (path:/tmp/pip-fhPOPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:26 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)) 07:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:26 INFO - Running setup.py (path:/tmp/pip-FLnQ05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:26 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)) 07:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:26 INFO - Running setup.py (path:/tmp/pip-pj5EC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:26 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)) 07:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:26 INFO - Running setup.py (path:/tmp/pip-T7QMqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:26 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)) 07:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:26 INFO - Running setup.py (path:/tmp/pip-CqVIjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:26 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)) 07:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:26 INFO - Running setup.py (path:/tmp/pip-ErOsoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:26 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)) 07:07:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:26 INFO - Running setup.py (path:/tmp/pip-T5iWqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:26 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)) 07:07:26 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)) 07:07:26 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)) 07:07:26 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)) 07:07:26 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)) 07:07:26 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)) 07:07:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:28 INFO - Downloading blessings-1.6.tar.gz 07:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:07:28 INFO - Installing collected packages: blessings 07:07:28 INFO - Running setup.py install for blessings 07:07:28 INFO - Successfully installed blessings 07:07:28 INFO - Cleaning up... 07:07:28 INFO - Return code: 0 07:07:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:07:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:28 INFO - Reading from file tmpfile_stdout 07:07:28 INFO - Current package versions: 07:07:28 INFO - argparse == 1.2.1 07:07:28 INFO - blessings == 1.6 07:07:28 INFO - blobuploader == 1.2.4 07:07:28 INFO - docopt == 0.6.1 07:07:28 INFO - functools32 == 3.2.3-2 07:07:28 INFO - jsonschema == 2.5.1 07:07:28 INFO - manifestparser == 1.1 07:07:28 INFO - mozInstall == 1.12 07:07:28 INFO - mozcrash == 0.17 07:07:28 INFO - mozdebug == 0.1 07:07:28 INFO - mozdevice == 0.48 07:07:28 INFO - mozfile == 1.2 07:07:28 INFO - mozhttpd == 0.7 07:07:28 INFO - mozinfo == 0.9 07:07:28 INFO - mozleak == 0.1 07:07:28 INFO - mozlog == 3.3 07:07:28 INFO - moznetwork == 0.27 07:07:28 INFO - mozprocess == 0.23 07:07:28 INFO - mozprofile == 0.28 07:07:28 INFO - mozrunner == 6.12 07:07:28 INFO - mozscreenshot == 0.1 07:07:28 INFO - mozsystemmonitor == 0.3 07:07:28 INFO - moztest == 0.7 07:07:28 INFO - mozversion == 1.4 07:07:28 INFO - psutil == 3.1.1 07:07:28 INFO - requests == 1.2.3 07:07:28 INFO - wsgiref == 0.1.2 07:07:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:28 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')]} 07:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:28 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')]} 07:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:28 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 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:28 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 07:07:28 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 07:07:28 INFO - Using env: {'DISPLAY': ':0', 07:07:28 INFO - 'HOME': '/home/cltbld', 07:07:28 INFO - 'LANG': 'en_US.UTF-8', 07:07:28 INFO - 'LANGUAGE': 'en_US:en', 07:07:28 INFO - 'LOGNAME': 'cltbld', 07:07:28 INFO - 'MAIL': '/var/mail/cltbld', 07:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:28 INFO - 'MOZ_NO_REMOTE': '1', 07:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:28 INFO - 'NO_EM_RESTART': '1', 07:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:28 INFO - 'PWD': '/builds/slave/test', 07:07:28 INFO - 'SHELL': '/bin/bash', 07:07:28 INFO - 'SHLVL': '1', 07:07:28 INFO - 'TERM': 'linux', 07:07:28 INFO - 'TMOUT': '86400', 07:07:28 INFO - 'USER': 'cltbld', 07:07:28 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:28 INFO - '_': '/tools/buildbot/bin/python'} 07:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 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)) 07:07:29 INFO - Cleaning up... 07:07:29 INFO - Return code: 0 07:07:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95a1793f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26f08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27bf230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, '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': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', '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 07:07:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:07:29 INFO - Using env: {'DISPLAY': ':0', 07:07:29 INFO - 'HOME': '/home/cltbld', 07:07:29 INFO - 'LANG': 'en_US.UTF-8', 07:07:29 INFO - 'LANGUAGE': 'en_US:en', 07:07:29 INFO - 'LOGNAME': 'cltbld', 07:07:29 INFO - 'MAIL': '/var/mail/cltbld', 07:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:29 INFO - 'MOZ_NO_REMOTE': '1', 07:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:29 INFO - 'NO_EM_RESTART': '1', 07:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:29 INFO - 'PWD': '/builds/slave/test', 07:07:29 INFO - 'SHELL': '/bin/bash', 07:07:29 INFO - 'SHLVL': '1', 07:07:29 INFO - 'TERM': 'linux', 07:07:29 INFO - 'TMOUT': '86400', 07:07:29 INFO - 'USER': 'cltbld', 07:07:29 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:29 INFO - '_': '/tools/buildbot/bin/python'} 07:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:07:29 INFO - Cleaning up... 07:07:29 INFO - Return code: 0 07:07:29 INFO - Running post-action listener: _resource_record_post_action 07:07:29 INFO - Running post-action listener: _start_resource_monitoring 07:07:29 INFO - Starting resource monitoring. 07:07:29 INFO - [mozharness: 2016-08-18 14:07:29.307237Z] Finished create-virtualenv step (success) 07:07:29 INFO - [mozharness: 2016-08-18 14:07:29.307748Z] Running install step. 07:07:29 INFO - Running pre-action listener: _resource_record_pre_action 07:07:29 INFO - Running main action method: install 07:07:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:29 INFO - Reading from file tmpfile_stdout 07:07:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:07:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:07:29 INFO - Reading from file tmpfile_stdout 07:07:29 INFO - Output received: 07:07:29 INFO - Usage: mozinstall [options] installer 07:07:29 INFO - Options: 07:07:29 INFO - -h, --help show this help message and exit 07:07:29 INFO - -d DEST, --destination=DEST 07:07:29 INFO - Directory to install application into. [default: 07:07:29 INFO - "/builds/slave/test"] 07:07:29 INFO - --app=APP Application being installed. [default: firefox] 07:07:29 INFO - mkdir: /builds/slave/test/build/application 07:07:29 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'] 07:07:29 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 07:07:45 INFO - Reading from file tmpfile_stdout 07:07:45 INFO - Output received: 07:07:45 INFO - /builds/slave/test/build/application/firefox/firefox 07:07:45 INFO - Running post-action listener: _resource_record_post_action 07:07:45 INFO - [mozharness: 2016-08-18 14:07:45.843869Z] Finished install step (success) 07:07:45 INFO - [mozharness: 2016-08-18 14:07:45.844117Z] Running run-tests step. 07:07:45 INFO - Running pre-action listener: _resource_record_pre_action 07:07:45 INFO - Running main action method: run_tests 07:07:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:07:45 INFO - Python 2.7.3 07:07:45 INFO - Return code: 0 07:07:45 INFO - grabbing minidump binary from tooltool 07:07: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')]} 07:07:45 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 0x27b9090>, '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 0x27bc9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27bce40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:07:45 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 07:07:45 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 07:07:45 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 07:07:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:07:45 INFO - Return code: 0 07:07:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:07:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:07:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:07:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:07:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:07:45 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/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 07:07:45 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/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --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 07:07:45 INFO - Using env: {'DISPLAY': ':0', 07:07:45 INFO - 'HOME': '/home/cltbld', 07:07:45 INFO - 'LANG': 'en_US.UTF-8', 07:07:45 INFO - 'LANGUAGE': 'en_US:en', 07:07:45 INFO - 'LOGNAME': 'cltbld', 07:07:45 INFO - 'MAIL': '/var/mail/cltbld', 07:07:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:07:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:45 INFO - 'MOZ_NO_REMOTE': '1', 07:07:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:07:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:45 INFO - 'NO_EM_RESTART': '1', 07:07:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:45 INFO - 'PWD': '/builds/slave/test', 07:07:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:07:45 INFO - 'SHELL': '/bin/bash', 07:07:45 INFO - 'SHLVL': '1', 07:07:45 INFO - 'TERM': 'linux', 07:07:45 INFO - 'TMOUT': '86400', 07:07:45 INFO - 'USER': 'cltbld', 07:07:45 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658', 07:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:45 INFO - '_': '/tools/buildbot/bin/python'} 07:07:45 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/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--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 07:07:46 INFO - mozversion application_buildid: 20160817173815 07:07:46 INFO - mozversion application_changeset: 30da32629a3c6ef74b8c66f819c08b1106f6da91 07:07:46 INFO - mozversion application_display_name: Nightly 07:07:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:07:46 INFO - mozversion application_name: Firefox 07:07:46 INFO - mozversion application_remotingname: firefox 07:07:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 07:07:46 INFO - mozversion application_vendor: Mozilla 07:07:46 INFO - mozversion application_version: 51.0a1 07:07:46 INFO - mozversion platform_buildid: 20160817173815 07:07:46 INFO - mozversion platform_changeset: 30da32629a3c6ef74b8c66f819c08b1106f6da91 07:07:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 07:07:46 INFO - mozversion platform_version: 51.0a1 07:07:46 INFO - using testdate: 1471529266 07:07:46 INFO - actual date: 1471529266 07:07:46 INFO - starting webserver on 'localhost:50075' 07:07:46 INFO - SUITE-START | Running 2 tests 07:07:46 INFO - TEST-START | dromaeo_css 07:07:46 INFO - operating with platform_type : linux_ 07:07:46 INFO - Initialising browser for dromaeo_css test... 07:07:46 INFO - TEST-INFO | started process 4740 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj6ZXpb/profile http://localhost:50075/getInfo.html) 07:07:50 INFO - PROCESS | 4740 | __metrics Screen width/height:1600/1200 07:07:50 INFO - PROCESS | 4740 | colorDepth:24 07:07:50 INFO - PROCESS | 4740 | Browser inner width/height: 1024/697 07:07:50 INFO - PROCESS | 4740 | __metrics 07:07:51 INFO - PROCESS | 4740 | 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" 07:07:51 INFO - PROCESS | 4740 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:07:52 INFO - TEST-INFO | 4740: exit 0 07:07:52 INFO - Browser initialized. 07:07:52 INFO - Running cycle 1/1 for dromaeo_css test... 07:07:52 INFO - TEST-INFO | started process 4819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj6ZXpb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:04 INFO - PROCESS | 4819 | RSS: Main: 157413376 07:08:04 INFO - PROCESS | 4819 | 07:08:55 INFO - PROCESS | 4819 | 1471529335298 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] 07:11:08 INFO - PROCESS | 4819 | Cycle 1(1): loaded http://localhost:50075/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50075/tests/dromaeo/cssquery-ext.html) 07:11:09 INFO - PROCESS | 4819 | RSS: Main: 232808448 07:11:09 INFO - PROCESS | 4819 | 07:14:12 INFO - PROCESS | 4819 | Cycle 1(1): loaded http://localhost:50075/tests/dromaeo/cssquery-ext.html (next: http://localhost:50075/tests/dromaeo/cssquery-jquery.html) 07:14:12 INFO - PROCESS | 4819 | RSS: Main: 251166720 07:14:12 INFO - PROCESS | 4819 | 07:17:15 INFO - PROCESS | 4819 | Cycle 1(1): loaded http://localhost:50075/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50075/tests/dromaeo/cssquery-mootools.html) 07:17:15 INFO - PROCESS | 4819 | RSS: Main: 216969216 07:17:15 INFO - PROCESS | 4819 | 07:20:17 INFO - PROCESS | 4819 | Cycle 1(1): loaded http://localhost:50075/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50075/tests/dromaeo/cssquery-prototype.html) 07:20:18 INFO - PROCESS | 4819 | RSS: Main: 188727296 07:20:18 INFO - PROCESS | 4819 | 07:23:21 INFO - PROCESS | 4819 | Cycle 1(1): loaded http://localhost:50075/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50075/tests/dromaeo/cssquery-yui.html) 07:23:22 INFO - PROCESS | 4819 | RSS: Main: 189407232 07:23:22 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | Cycle 1(1): loaded http://localhost:50075/tests/dromaeo/cssquery-yui.html (next: http://localhost:50075/tests/dromaeo/cssquery-dojo.html) 07:26:25 INFO - PROCESS | 4819 | RSS: Main: 271577088 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | __start_tp_report 07:26:25 INFO - PROCESS | 4819 | _x_x_mozilla_page_load 07:26:25 INFO - PROCESS | 4819 | _x_x_mozilla_page_load_details 07:26:25 INFO - PROCESS | 4819 | |i|pagename|runs| 07:26:25 INFO - PROCESS | 4819 | |0;dojo.html;2584.9353766155846;2610.0995156670965;2610.634511168437;2633.3416645838543;2634.4467661791023;4500.1674690182335;4549.181147393468;4668.299755036742;4708.340832283478;4713.929291060636;4593.058423023281;4616.676832621718;4620.653450991173;4649.070185962804;4698.577128058474;4237.343211802176;4241.618254357108;4315.71947823392;4363.934540981872;4411.250087485135;4328.935065973998;4353.978230108845;4394.868153955386;4401.141777353418;4412.911741765162;4203.91592168155;4255.234057869582;4323.632491238217;4332.176886391576;4400.229959757045;4187.057911969813;4210.6841986851105;4217.346311321719;4243.681723870722;4255.659547236244;4219.2194444027955;4297.634701050414;4300.849470268557;4325.243082460557;4362.694611377205;4510.368548403226;4665.2535594304745;4669.089527542131;4700.764961751899;4709.929351059737;4927.556519913189;4968.602511799084;4973.079980203674;4985.426675932278;5010.624203184776;5041.495850414965;5058.038972595232;5066.392032956032;5086.1353569892835;5098.311727916758;4530.524294949048;4599.1721490132095;4761.547652972928;4761.928571071437;4765.332853400527;5054.7472626368535;5130.230465430215;5157.871053223625;5176.249443830623;5177.404598471224;4916.975415122902;4951.133551628451;4953.554180123806;4959.057779021974;4960.206366981266;4997.650164488524;5001.649884508122;5038.3198268234055;5041.899162016739;5043.268726034705;4827.179379505493;4879.926801097987;4941.703497790144;4947.727874966911;5031.52200540948;4950.331705219821;4982;4982.3522942018035;5005.224190250517;5022.824201153016;4250.3836943643;4255.5744425557195;4261.74429534229;4276.294411422143;4287.12114016632;3642.581103173116;3667.339878821784;3679.8712045078296;3721.702263818888;3786.450964610116;4300.88177073963;4336.566343365701;4337.60961513459;4371.846985355561;4373.497047839475;4990.6506544541535;5025.723585202849;5027.748612569357;5034.84895453137;5053.570446512088;4210.6841986851105;4258.850940217139;4339.7830108494445;4342.522322544518;4374.5625437456;4482.641388688897;4497.482789479186;4515.68390212682;4550.635949124062;4552.203364411214;4712.95287047132;4714.528547145258;4717.693349932243;4718.551737584924;4753.572178503882;4881;4947.851564453073;4992.6255530834605;4996.575291100297;5005.074061298671;4712.69367491112;4740.146773580788;4770.427548694179;4806.519348065305;4811.518848115161;4948.529889660621;4951.480094590161;4954.727489987941;4966.553010229097;4995.325631039908;4829.203181475017;4857.1742803724155;5007.8748031298055;5025.874353141201;5073.086844368049;4837.129316723023;4837.612990960644;4859.635527335393;4894.265860120868;4896.4124305083615;4801.303810947463;4821.9035619286715;4824.541668541483;4826.686265392738;4863.392075990501;4661.953380466288;4755.690880092783;4776.402949631296;4800;4829.517048295143;4882.511748825089;4890;4933.654644175017;4993.475685053162;5000.949990500194;4816.34979277793;4828.37231159947;4829.082474329866;4833;4878.243872199815 07:26:25 INFO - PROCESS | 4819 | |1;ext.html;570.777396815242;578.7684926029589;579.2064871126557;580.518169918967;597.8176656119887;28883.27791805206;31040.53439198411;31084;42166.57833421665;44024.496937882766;40328.79835600825;40624.59375406245;40659;40724.59275407245;40757.79621101898;7608.12506561747;7748.108967468757;7751.379889608846;7758.185390533998;7758.456908016441;7615.124260710034;7696.153423123452;7703.537787732754;7703.614819259043;7705.73029943952;7654.655540500691;7697.961510192441;7698.230176982313;7700.306972372486;7710.344620707247;7650.234976502361;7651.617419129049;7673.654685539164;7685.03937007874;7695.307422331961;7622.856571514317;7687.308142267167;7688.115866675348;7695.846083078363;7716.382689384835;38664.80667596672;39035;39061.41407878884;39062.21875562473;39084;3119.5632611434417;3360.4455264881267;3458.058083011342;3707.0917625181733;3793.2603142387247;28523.714762852313;28758.71241287581;28891.711082889113;28969;30263.69736302631;3022.863971121305;3049.7102775236317;3117.06488053583;3122.0945925681526;3140.6388265349547;257521;257839;258358;258521;261136.69431652912;50721.74639126855;51137.744311278206;51155.744221278655;51341;51475.48524514707;32963;33712.831435842665;33771.15572110717;35296.470552941726;55239.990201420565;13835.861641383659;13840.30798460073;13850.238236896867;13903.582892513241;13914;10322.948385258176;10388.220883433773;10422.5830966762;10437.478126093665;10470.16238700902;17961.820381796275;17968;17986.37047703324;18015.18931648079;18243;6568.868622627616;6814.250432452426;6875.518713690094;6907.274736152732;6952.270011648805;753.4800987318732;759.5822297736223;818.0265484073933;862.3661608717588;910.0535443139062;26797.598036029474;27266.591001135;27447.451050979267;27726;28194.85902570474;769.830637259802;942.0579420579421;950.6815216902415;955.6798472511647;1002.5388321372104;13363.93318033423;14584.343704533321;14685.706285874223;14968.17675027862;15010.699786004438;401813;403728;403767;404422;404509;46194.5380546197;47655.28517072247;47979.76010119997;48033.27950080752;48130;1053.0838170791308;1105.1048650592884;1128.6388355726253;1145.255583870474;1151.4875880233383;27697;28775.85612071926;29389.26526836846;29474.557881632223;29966.25084372908;41581.37627935644;42185;42194.78902605467;42234.5776542243;42236.155276893674;252417;254173;254273;254591;394705;36153.45769813402;36275.63724362723;36455.817720911225;36462.4530632046;37163.81418092892;41050.79474602608;41896.3715544261;41927.790361048;42293.15413691771;42439.78780106203;36437.71653110231;36493.08767280839;36809.07977300589;36879;37055.62944370522;35522.289554208255;35907.84088479124;35951.28097438089;36888.81555592205;37538.624613754604;35622.28755424929;35699.28601427905;35814.4627830588;36150.63849361578;36730.8163459181;30451.847740761154;33447.33105337928;33529.16177095592;33532.32935341328;33703.15742106467;24975.375615609028;25252.87373563194;25967.220983370527;26107;26423.33941651474 07:26:25 INFO - PROCESS | 4819 | |2;jquery.html;341.43833394066746;351.58688540964374;359.86684926577163;363.99454008189883;381.5192857000181;12705;13348.93325533371;13656.931715341421;17278;17578.472645820613;15512.379504819794;16396.590085247874;16439.506814795575;16513.83486165138;16551.668966620662;4901.754912254391;4993.425756038047;5110.233464980245;5133.717645529495;5138.897222055557;3711.0907827582146;5011.699298042111;5082.440931497532;5083.288339632454;5728.68492232927;6030.758769649209;6079.696015199244;6117.5717699761035;6119.0209566469375;6122.969385153068;5868.677222752725;5938.703064846741;5967.6419414835245;5970.164177015221;6097.14639950405;5220.29526013987;5223.712695801767;5225.581953443715;5226.55574276187;5230.058589453897;19952;20633.793662063337;20650;20690.896545517175;20820.47948801277;1798.3615816385256;1854.1470923375264;1867.8692491525599;1930.6138772245606;2372.5254949010096;12388.690282742913;12714.173578717353;13087.86912130876;13298.202107873558;13528.594142175652;1592.904425734448;1655.1062426289789;1671.4901954903748;1688.0293831047074;1695.8728095392771;68457;68501.65749171271;69362.65318673424;70121.64939175322;70175.64912175457;9242.630294788134;9367.62529498826;9436;9612.231021518262;9690.70927872165;12981.350932453528;12998.220106793622;13018;13023.739525209443;13033.348332583333;6644;6750.088738020305;6835.658217089126;6854.34883686049;6924.826879327957;6112.969435152885;6126.846828829226;6177.907331390033;6269.7492100316385;6413.871722565523;5937.910931336033;5970.283565972111;5999.310079340845;6050.788222412194;6056.515478761688;1735.9305627775;1740.8868423552428;1758.2088060372882;1759.6304775997185;1783.4025601423668;761.9256847844623;780.3406121827047;882.8611091691646;904.7059705595708;975.6243846119295;16572.254248558846;16645.834791564474;17044;17114.657706845792;17564;802.3982013489882;819.3649921311031;842.1073661918387;865.8960924688952;907.7957459571544;9445.291603129794;9954.502274886227;10030.297879148535;10051;10065.094141527297;375605;383832;386617;387911;389590;47803.76098119557;62014;65348;65999;70586.294137059;875.1685898396548;901.544719916438;917.788908551037;942.9622815087457;945.1588086602928;14398.712025759216;14859.419793017152;16331.510054698378;16390.262438190548;16573.585660358105;34054.8297258522;36017.81991090133;36062.819685901406;36499.81750091232;37222.81388593148;42916;43136.92385615383;43203;45424.318635221156;45467.772661136485;4951.653384263067;7059.764541205372;7460.29127232912;7514.549127052176;7534.133574638987;11220;11301.491432885705;11413.144014199135;11754.471048802654;11809;9570.61717531291;9729.270304727315;9753.707388778348;9857.802843942938;10177.84733229017;9037.50293733851;9789.755256118655;9883.802323953625;9930.106290433896;9943.25425593069;9396.718098457057;9480.478573678238;9882.061204185591;10162.491875406347;10345.948270258601;7411.592362420119;7574.356179724785;7704.6918123274445;7706.807329816799;7809.297163255334;8995.955020224857;9454.905450945402;9496.952515237379;9527.952360238154;10112.443815590213 07:26:25 INFO - PROCESS | 4819 | |3;mootools.html;1351.8850897673694;1385.2450414524085;1442.798008278841;1638.565780068283;1651.2569343795287;7313.305236002585;7895.881561776578;7898.210178982099;7905.683772649088;7919.604019799009;6786.830329241771;6974.8256293592685;6982.615956122411;7160.462965277599;7882.448228623999;1861.934832280874;2139.411661793004;2204.173434961889;2205.0297868937696;2223.299660606912;2153.9892300538477;2155.568886222754;2158.56828634273;2159.114762947192;2160.967585486219;2104.8000439958255;2182.301663467691;2184.8142907852853;2186.6719992001167;2188.890555472228;2105.6420408530585;2109.1247132440094;2114.9577008459823;2146.1415433826437;2148.022649694385;2058.8249998750125;2061.3815855243365;2062.669972804359;2066.442060643633;2067.0388269454625;7584.696612135508;7602.163761986177;7638.732644357421;7925.643346049442;8008.239217274349;4403.141387429452;4515.571020753023;4517.073999830027;4520.909581808378;4526.592606665416;2716.348076461635;2841.3748975225417;2869.7130286971346;2876.913692589226;2887.5091234490187;930.3394589841221;934.6541779541545;939.8543225800012;942.4533770413144;954.3605784124653;10609.73475663107;10616.946915265375;10619.681409557727;10629.787404251949;10631.787364252748;4129.359949207877;4178.519470260898;4203.138356636943;4207.97896010518;4275.29457639486;9097.226735727405;9102.90897091034;9135.588898499584;9146.771330716652;9153.908460915438;4391.956080439155;4392.538783427758;4393.956060439418;4397.9560204398185;4400.581944715247;3907.6092390760696;3968.6229808168177;3990.3216453203027;3992.041909941593;3996.9600303997167;3279.983600081984;3496.7727097738566;3586.802725850051;3634.945475817865;3668.944965825515;1197.6047904191596;1200.393801130441;1205.4997176171967;1207.5290636651712;1208.3173007250875;846.9165852004016;847.563504795031;856.5631527920805;872.5942436766934;889.768660148366;5294;5463.590230732711;5498.3127109111365;5553.722313884289;5564.693941833238;846.9407141500159;868.3313848336871;868.3617541107408;872.5070335260557;921.4056933277998;3731.3470142724914;3789.6589306961955;3799.848006079781;3808.980955095207;3834.2714884172474;9060;9445.952770236105;9447.811043779086;9484.478353690612;9492.430454172772;9333.673321433716;9344.159025687584;9350.67272645454;9358;9383.906160938439;7148.535345202649;7195.532290401212;7225.927740722525;7227.241139680258;7235;5156.535911767809;5208.453112423293;5227.451117632746;5308.336457942757;5309.65487243328;8476.23713865755;8619.741407757778;8643.222110010129;8725.91274087251;8764.255038321558;8650.56747162652;8859.734207973772;8895.021547629625;8896.421732587361;8911.55442227899;6514.413702766774;6727.428168605723;6782.355676210949;6828.9658551706925;6843.692033858389;6480.287168411565;6929.792106236821;7095.538789978635;7168.605726685082;7349.779506614596;6511.609303441809;6539.934600653933;6570.5729127606555;6584.736610535525;6611.504137189634;6438.8390290243115;6441.066045423481;6456.806295810945;6490.448311893542;6525.086487891794;6059.394060594082;6133.080037994337;6141.907871382022;6144.17053697763;6149.139120523219;4527.4340707411575;4538.2511885538515;4555.681102322938;4617.653675974382;4699.718016918996;4362.127574485179;4415.381846541551;4473.418455600751;4487.596116349544;4590.862274131781 07:26:25 INFO - PROCESS | 4819 | |4;prototype.html;276.35195466630745;284.37295762842933;286.95405247871497;287.7870375921818;297.7246047406149;4915.70505769654;4956.794226569178;4964.8014079436825;4968.552830245278;4991.625628077895;4931.65478416511;4932.235503496955;4936.605071594273;4937.136001199785;4942.851714448563;3632.4551317302353;3724.9255014899686;3765.548134223897;3776.8678096266635;3854.8265328060165;3815.866444674437;3816.503854498911;3828.3874580067195;3840.404737265714;3842.4812650292274;3807.1433927366397;3816.618338166189;3819.713521485886;3831.2337532493475;3842.654161125498;3623.8188090595363;3653.4702468142104;3784.829682664259;3786.2616789726017;3786.848526058954;3361.8151001694896;3371.123507887942;3373.245912125681;3378.4932260160926;3381.7294616430627;4713.787879545429;4770.976145119287;4796.112719146969;4829.396325459317;4861.465238823726;4128.979355103235;4304.837693822682;4384.912301753947;4427.557244275579;4432.22436073689;5106.523336181949;5108.157154069574;5110.5911527078115;5114.539691427753;5129.307543481619;3652.8356223969986;3665.9450108248398;3668.7798732075894;3677.1174918019483;3684.1158122050783;5249.842504724865;5370.3287089113865;5382.354117505885;5442.836714898559;5463.2078348639225;3649.489071529988;3669.2478042001326;3673.5856695172015;3677.2277821657754;3693.3351996640326;3744.7718607428537;3747.512823333004;3749.1564398010228;3752.7373083884413;3753.7560058596105;4064.898377540585;4258.808353624095;4292.5063617683745;4315.136972605492;4324.17840610283;5052.595792336604;5053.6898331592865;5060.84817455477;5071.822486212965;5077.822276220315;1271.9809202861964;1297.8442586889635;1304.3282709404664;1304.439091190778;1310.8164856920039;812.9512229266263;827.5779352530133;830.5058490198322;833.1709948601059;896.7713233125642;4419.895026243439;4462.41988541494;4469.262571675637;4481.708688935209;4484.887877803081;5227.536289838851;5231.273679685694;5232.92150617741;5241.318628578337;5241.375173696167;4293.484781826201;4481.820727170942;4487.865364039084;4490.528494508095;4491.932621010687;2773.278947473631;2780.527310357244;2785.206216228365;2792.9860350698523;2793.580962855547;4876.366072410635;4882.6826256293225;4927;4936.827211047596;4940.308356830046;5119.87200319995;5348.465153484698;5349.170878513836;5359.866003349947;5382.569394448513;4876.366072410635;4879.829205977845;4882.292067650242;4887.926681099858;4909.165441874889;5130.153524668537;5170.784865556667;5181.922271166011;5183.974080129575;5198.818041368534;4949;5036.370453693288;5105.7191854448365;5112.721819545114;5132.409772876167;4882.511748825089;4886.609071274361;4894.412670479423;4904.768330409726;4906.779194936308;3810.1236715555024;3820.446035324807;3843.577206507338;3843.769373837467;3844.596317386634;5047.772850221822;5175;5279.47205279469;5281.313429254173;5284.550813180923;4974.527419895104;4978.626603004862;4992.226204938241;5004.224345226496;5038.571721403721;4958.45456999737;5021.44764075944;5054.873628159326;5057.266696329085;5070.391553013662;4781.211100168433;4961.668016032459;4975.5522003019905;4989.600831933364;5031.3207716959205;4945;5026.673266237683;5171.715555644476;5172.844814655567;5177.223416487557;5060.3168572242275;5069.949300506948;5094.872628184325;5096.184610462308;5101.362329708786 07:26:25 INFO - PROCESS | 4819 | |5;yui.html;633.9587926784759;663.1147418196707;687.2955220898575;717.6196615793627;738.1253214940297;11899.821502677456;12872.935635321834;12884.484620615163;12898.871011289884;12905.29020903851;12475.18911270769;12531.561395351164;12551.748965020694;12554.183978041421;12574.68563285916;609.7256234694405;737.1743647115238;742.9257074292582;768.2739810878721;772.1622040086513;768.5196752029981;770.306723948448;771.0439055571079;771.1209221487509;772.1583474013324;802.8514724775906;803.220875750524;803.2931020701803;803.3813963248296;803.7628899474669;643.4852118305361;657.6382989355883;661.0348890619701;662.9867402651968;801.1027649033091;573.2404563952779;586.6069733278714;587.2395248153651;592.0586267834165;596.9880602387972;11147.386893720843;11192.38441885696;11206.663800086011;11221.887781122248;11245.381504017276;12043.397830108546;12357.752844943141;12374.257544547356;12472.81290780639;12900;10491.58033678652;11049.895010498965;11253.156013299036;11378.431078446045;11659.358735269558;3853.6724378427866;3880.0881792778673;3911.119998000456;4099.282625540548;4137.8758637240935;65820.3417965819;65843;67514.3248567513;68908.3109168907;69072.65463672699;9304.348695591243;9530.714078577654;10075.647352342632;10341.793164136676;10395.896041039645;18302.908485457487;18568.164432600566;18569.814301856808;18573.628527429377;18610.906945465187;6309.08518264849;6310.716017779119;6351.682415879188;6503.317151699098;6618.371254730793;3488.337215928965;3493.161641206092;3512.2799826035603;3541.8937431877084;3634.8727794527595;2772.7227277272113;2848.145556333092;2885.4373397187346;2886.3794284228993;2893.667228268734;479.47976445556264;480.9975950120297;495.44015262752856;497.0407114269495;499.1090902738667;495.6753326571102;511.5217271850757;520.828126718182;528.6827903258014;552.9466366571687;6882.186550803577;7393.371563417062;7522.322990930843;8480.194381533744;8560.272376847914;455.22384334709244;531.3252169744403;570.2842932120162;572.6306532286652;586.9236999190078;3529.8411571479346;3861.575226725058;3863.150106976461;3999.6600288975183;4021.195760847842;93786.53106734423;94048;95957.5202123985;96080.51959740157;99598.03215742724;78784;80825.59587202026;81244.18755812367;81864.59067704623;83459.5827020861;70475;70653.2934670657;72629.6368518154;74102.2589774117;76426.61786691031;7524.661390237343;7560.6219689016425;7593.392528597588;7774.494657847222;7893.486923349964;42308.57691423169;42380.57619423766;42877;42886;43210.78394608007;53281.20078198753;55122.72438637781;57871.71064144652;59488;59774;10554.472276386303;10738.355698658106;10891;10957.342559446459;11159.832602511131;12103.697407564881;12245.816312755138;12249.510019599116;12403.62789116328;12840;10282.228832837716;10817.567297308335;11052;11141.108711302913;11144.832827507757;10870.728231794268;11406.258593191416;11813.645590632295;11856.347900865536;11861.169718119994;10067.597296108366;10742.19433542503;10879.075278601407;10904.454777261264;10905.291156074829;8734.868976965223;8738.868916966123;9141.817163656557;9411.623535058521;9486.288528360263;8101.959490202511;8951.865722014045;8983.640654373752;9034.683786067457;9120.863187052068 07:26:25 INFO - PROCESS | 4819 | __end_tp_report 07:26:25 INFO - PROCESS | 4819 | __start_cc_report 07:26:25 INFO - PROCESS | 4819 | _x_x_mozilla_cycle_collect,993 07:26:25 INFO - PROCESS | 4819 | __end_cc_report 07:26:25 INFO - PROCESS | 4819 | __startTimestamp1471530385735__endTimestamp 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | ------- Summary: start ------- 07:26:25 INFO - PROCESS | 4819 | Number of tests: 6 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | [#0] dojo.html Cycles:165 Average:4623.51 Median:4761.93 stddev:484.26 (10.2%) stddev-sans-first:458.58 07:26:25 INFO - PROCESS | 4819 | Values: 2584.9 2610.1 2610.6 2633.3 2634.4 4500.2 4549.2 4668.3 4708.3 4713.9 4593.1 4616.7 4620.7 4649.1 4698.6 4237.3 4241.6 4315.7 4363.9 4411.3 4328.9 4354.0 4394.9 4401.1 4412.9 4203.9 4255.2 4323.6 4332.2 4400.2 4187.1 4210.7 4217.3 4243.7 4255.7 4219.2 4297.6 4300.8 4325.2 4362.7 4510.4 4665.3 4669.1 4700.8 4709.9 4927.6 4968.6 4973.1 4985.4 5010.6 5041.5 5058.0 5066.4 5086.1 5098.3 4530.5 4599.2 4761.5 4761.9 4765.3 5054.7 5130.2 5157.9 5176.2 5177.4 4917.0 4951.1 4953.6 4959.1 4960.2 4997.7 5001.6 5038.3 5041.9 5043.3 4827.2 4879.9 4941.7 4947.7 5031.5 4950.3 4982.0 4982.4 5005.2 5022.8 4250.4 4255.6 4261.7 4276.3 4287.1 3642.6 3667.3 3679.9 3721.7 3786.5 4300.9 4336.6 4337.6 4371.8 4373.5 4990.7 5025.7 5027.7 5034.8 5053.6 4210.7 4258.9 4339.8 4342.5 4374.6 4482.6 4497.5 4515.7 4550.6 4552.2 4713.0 4714.5 4717.7 4718.6 4753.6 4881.0 4947.9 4992.6 4996.6 5005.1 4712.7 4740.1 4770.4 4806.5 4811.5 4948.5 4951.5 4954.7 4966.6 4995.3 4829.2 4857.2 5007.9 5025.9 5073.1 4837.1 4837.6 4859.6 4894.3 4896.4 4801.3 4821.9 4824.5 4826.7 4863.4 4662.0 4755.7 4776.4 4800.0 4829.5 4882.5 4890.0 4933.7 4993.5 5000.9 4816.3 4828.4 4829.1 4833.0 4878.2 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | [#1] ext.html Cycles:180 Average:46733.55 Median:36883.91 stddev:85065.06 (230.6%) stddev-sans-first:85233.08 07:26:25 INFO - PROCESS | 4819 | Values: 570.8 578.8 579.2 580.5 597.8 28883.3 31040.5 31084.0 42166.6 44024.5 40328.8 40624.6 40659.0 40724.6 40757.8 7608.1 7748.1 7751.4 7758.2 7758.5 7615.1 7696.2 7703.5 7703.6 7705.7 7654.7 7698.0 7698.2 7700.3 7710.3 7650.2 7651.6 7673.7 7685.0 7695.3 7622.9 7687.3 7688.1 7695.8 7716.4 38664.8 39035.0 39061.4 39062.2 39084.0 3119.6 3360.4 3458.1 3707.1 3793.3 28523.7 28758.7 28891.7 28969.0 30263.7 3022.9 3049.7 3117.1 3122.1 3140.6 257521.0 257839.0 258358.0 258521.0 261136.7 50721.7 51137.7 51155.7 51341.0 51475.5 32963.0 33712.8 33771.2 35296.5 55240.0 13835.9 13840.3 13850.2 13903.6 13914.0 10322.9 10388.2 10422.6 10437.5 10470.2 17961.8 17968.0 17986.4 18015.2 18243.0 6568.9 6814.3 6875.5 6907.3 6952.3 753.5 759.6 818.0 862.4 910.1 26797.6 27266.6 27447.5 27726.0 28194.9 769.8 942.1 950.7 955.7 1002.5 13363.9 14584.3 14685.7 14968.2 15010.7 401813.0 403728.0 403767.0 404422.0 404509.0 46194.5 47655.3 47979.8 48033.3 48130.0 1053.1 1105.1 1128.6 1145.3 1151.5 27697.0 28775.9 29389.3 29474.6 29966.3 41581.4 42185.0 42194.8 42234.6 42236.2 252417.0 254173.0 254273.0 254591.0 394705.0 36153.5 36275.6 36455.8 36462.5 37163.8 41050.8 41896.4 41927.8 42293.2 42439.8 36437.7 36493.1 36809.1 36879.0 37055.6 35522.3 35907.8 35951.3 36888.8 37538.6 35622.3 35699.3 35814.5 36150.6 36730.8 30451.8 33447.3 33529.2 33532.3 33703.2 24975.4 25252.9 25967.2 26107.0 26423.3 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | [#2] jquery.html Cycles:180 Average:23495.35 Median:5121.98 stddev:63206.23 (1234.0%) stddev-sans-first:63359.63 07:26:25 INFO - PROCESS | 4819 | Values: 341.4 351.6 359.9 364.0 381.5 12705.0 13348.9 13656.9 17278.0 17578.5 15512.4 16396.6 16439.5 16513.8 16551.7 4901.8 4993.4 5110.2 5133.7 5138.9 3711.1 5011.7 5082.4 5083.3 5728.7 6030.8 6079.7 6117.6 6119.0 6123.0 5868.7 5938.7 5967.6 5970.2 6097.1 5220.3 5223.7 5225.6 5226.6 5230.1 19952.0 20633.8 20650.0 20690.9 20820.5 1798.4 1854.1 1867.9 1930.6 2372.5 12388.7 12714.2 13087.9 13298.2 13528.6 1592.9 1655.1 1671.5 1688.0 1695.9 68457.0 68501.7 69362.7 70121.6 70175.6 9242.6 9367.6 9436.0 9612.2 9690.7 12981.4 12998.2 13018.0 13023.7 13033.3 6644.0 6750.1 6835.7 6854.3 6924.8 6113.0 6126.8 6177.9 6269.7 6413.9 5937.9 5970.3 5999.3 6050.8 6056.5 1735.9 1740.9 1758.2 1759.6 1783.4 761.9 780.3 882.9 904.7 975.6 16572.3 16645.8 17044.0 17114.7 17564.0 802.4 819.4 842.1 865.9 907.8 9445.3 9954.5 10030.3 10051.0 10065.1 375605.0 383832.0 386617.0 387911.0 389590.0 47803.8 62014.0 65348.0 65999.0 70586.3 875.2 901.5 917.8 943.0 945.2 14398.7 14859.4 16331.5 16390.3 16573.6 34054.8 36017.8 36062.8 36499.8 37222.8 42916.0 43136.9 43203.0 45424.3 45467.8 4951.7 7059.8 7460.3 7514.5 7534.1 11220.0 11301.5 11413.1 11754.5 11809.0 9570.6 9729.3 9753.7 9857.8 10177.8 9037.5 9789.8 9883.8 9930.1 9943.3 9396.7 9480.5 9882.1 10162.5 10345.9 7411.6 7574.4 7704.7 7706.8 7809.3 8996.0 9454.9 9497.0 9528.0 10112.4 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | [#3] mootools.html Cycles:180 Average:5049.98 Median:5386.62 stddev:2811.32 (52.2%) stddev-sans-first:2805.47 07:26:25 INFO - PROCESS | 4819 | Values: 1351.9 1385.2 1442.8 1638.6 1651.3 7313.3 7895.9 7898.2 7905.7 7919.6 6786.8 6974.8 6982.6 7160.5 7882.4 1861.9 2139.4 2204.2 2205.0 2223.3 2154.0 2155.6 2158.6 2159.1 2161.0 2104.8 2182.3 2184.8 2186.7 2188.9 2105.6 2109.1 2115.0 2146.1 2148.0 2058.8 2061.4 2062.7 2066.4 2067.0 7584.7 7602.2 7638.7 7925.6 8008.2 4403.1 4515.6 4517.1 4520.9 4526.6 2716.3 2841.4 2869.7 2876.9 2887.5 930.3 934.7 939.9 942.5 954.4 10609.7 10616.9 10619.7 10629.8 10631.8 4129.4 4178.5 4203.1 4208.0 4275.3 9097.2 9102.9 9135.6 9146.8 9153.9 4392.0 4392.5 4394.0 4398.0 4400.6 3907.6 3968.6 3990.3 3992.0 3997.0 3280.0 3496.8 3586.8 3634.9 3668.9 1197.6 1200.4 1205.5 1207.5 1208.3 846.9 847.6 856.6 872.6 889.8 5294.0 5463.6 5498.3 5553.7 5564.7 846.9 868.3 868.4 872.5 921.4 3731.3 3789.7 3799.8 3809.0 3834.3 9060.0 9446.0 9447.8 9484.5 9492.4 9333.7 9344.2 9350.7 9358.0 9383.9 7148.5 7195.5 7225.9 7227.2 7235.0 5156.5 5208.5 5227.5 5308.3 5309.7 8476.2 8619.7 8643.2 8725.9 8764.3 8650.6 8859.7 8895.0 8896.4 8911.6 6514.4 6727.4 6782.4 6829.0 6843.7 6480.3 6929.8 7095.5 7168.6 7349.8 6511.6 6539.9 6570.6 6584.7 6611.5 6438.8 6441.1 6456.8 6490.4 6525.1 6059.4 6133.1 6141.9 6144.2 6149.1 4527.4 4538.3 4555.7 4617.7 4699.7 4362.1 4415.4 4473.4 4487.6 4590.9 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | [#4] prototype.html Cycles:180 Average:4228.97 Median:4882.60 stddev:1227.62 (25.1%) stddev-sans-first:1194.68 07:26:25 INFO - PROCESS | 4819 | Values: 276.4 284.4 287.0 287.8 297.7 4915.7 4956.8 4964.8 4968.6 4991.6 4931.7 4932.2 4936.6 4937.1 4942.9 3632.5 3724.9 3765.5 3776.9 3854.8 3815.9 3816.5 3828.4 3840.4 3842.5 3807.1 3816.6 3819.7 3831.2 3842.7 3623.8 3653.5 3784.8 3786.3 3786.8 3361.8 3371.1 3373.2 3378.5 3381.7 4713.8 4771.0 4796.1 4829.4 4861.5 4129.0 4304.8 4384.9 4427.6 4432.2 5106.5 5108.2 5110.6 5114.5 5129.3 3652.8 3665.9 3668.8 3677.1 3684.1 5249.8 5370.3 5382.4 5442.8 5463.2 3649.5 3669.2 3673.6 3677.2 3693.3 3744.8 3747.5 3749.2 3752.7 3753.8 4064.9 4258.8 4292.5 4315.1 4324.2 5052.6 5053.7 5060.8 5071.8 5077.8 1272.0 1297.8 1304.3 1304.4 1310.8 813.0 827.6 830.5 833.2 896.8 4419.9 4462.4 4469.3 4481.7 4484.9 5227.5 5231.3 5232.9 5241.3 5241.4 4293.5 4481.8 4487.9 4490.5 4491.9 2773.3 2780.5 2785.2 2793.0 2793.6 4876.4 4882.7 4927.0 4936.8 4940.3 5119.9 5348.5 5349.2 5359.9 5382.6 4876.4 4879.8 4882.3 4887.9 4909.2 5130.2 5170.8 5181.9 5184.0 5198.8 4949.0 5036.4 5105.7 5112.7 5132.4 4882.5 4886.6 4894.4 4904.8 4906.8 3810.1 3820.4 3843.6 3843.8 3844.6 5047.8 5175.0 5279.5 5281.3 5284.6 4974.5 4978.6 4992.2 5004.2 5038.6 4958.5 5021.4 5054.9 5057.3 5070.4 4781.2 4961.7 4975.6 4989.6 5031.3 4945.0 5026.7 5171.7 5172.8 5177.2 5060.3 5069.9 5094.9 5096.2 5101.4 07:26:25 INFO - PROCESS | 4819 | 07:26:25 INFO - PROCESS | 4819 | [#5] yui.html Cycles:180 Average:17276.55 Median:516.17 stddev:24898.14 (4823.6%) stddev-sans-first:24936.62 07:26:25 INFO - PROCESS | 4819 | Values: 634.0 663.1 687.3 717.6 738.1 11899.8 12872.9 12884.5 12898.9 12905.3 12475.2 12531.6 12551.7 12554.2 12574.7 609.7 737.2 742.9 768.3 772.2 768.5 770.3 771.0 771.1 772.2 802.9 803.2 803.3 803.4 803.8 643.5 657.6 661.0 663.0 801.1 573.2 586.6 587.2 592.1 597.0 11147.4 11192.4 11206.7 11221.9 11245.4 12043.4 12357.8 12374.3 12472.8 12900.0 10491.6 11049.9 11253.2 11378.4 11659.4 3853.7 3880.1 3911.1 4099.3 4137.9 65820.3 65843.0 67514.3 68908.3 69072.7 9304.3 9530.7 10075.6 10341.8 10395.9 18302.9 18568.2 18569.8 18573.6 18610.9 6309.1 6310.7 6351.7 6503.3 6618.4 3488.3 3493.2 3512.3 3541.9 3634.9 2772.7 2848.1 2885.4 2886.4 2893.7 479.5 481.0 495.4 497.0 499.1 495.7 511.5 520.8 528.7 552.9 6882.2 7393.4 7522.3 8480.2 8560.3 455.2 531.3 570.3 572.6 586.9 3529.8 3861.6 3863.2 3999.7 4021.2 93786.5 94048.0 95957.5 96080.5 99598.0 78784.0 80825.6 81244.2 81864.6 83459.6 70475.0 70653.3 72629.6 74102.3 76426.6 7524.7 7560.6 7593.4 7774.5 7893.5 42308.6 42380.6 42877.0 42886.0 43210.8 53281.2 55122.7 57871.7 59488.0 59774.0 10554.5 10738.4 10891.0 10957.3 11159.8 12103.7 12245.8 12249.5 12403.6 12840.0 10282.2 10817.6 11052.0 11141.1 11144.8 10870.7 11406.3 11813.6 11856.3 11861.2 10067.6 10742.2 10879.1 10904.5 10905.3 8734.9 8738.9 9141.8 9411.6 9486.3 8102.0 8951.9 8983.6 9034.7 9120.9 07:26:25 INFO - PROCESS | 4819 | -------- Summary: end -------- 07:26:25 INFO - PROCESS | 4819 | 07:26:26 INFO - TEST-INFO | 4819: exit 0 07:26:26 INFO - TEST-OK | dromaeo_css | took 1120460ms 07:26:26 INFO - TEST-START | kraken 07:26:26 INFO - operating with platform_type : linux_ 07:26:26 INFO - Initialising browser for kraken test... 07:26:26 INFO - TEST-INFO | started process 5123 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm7C8dp/profile http://localhost:50075/getInfo.html) 07:26:30 INFO - PROCESS | 5123 | __metrics Screen width/height:1600/1200 07:26:30 INFO - PROCESS | 5123 | colorDepth:24 07:26:30 INFO - PROCESS | 5123 | Browser inner width/height: 1024/697 07:26:30 INFO - PROCESS | 5123 | __metrics 07:26:31 INFO - PROCESS | 5123 | 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" 07:26:31 INFO - PROCESS | 5123 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:26:31 INFO - PROCESS | 5123 | console.error: PushService: 07:26:31 INFO - PROCESS | 5123 | stateChangeProcessEnqueue: Error transitioning state 07:26:31 INFO - PROCESS | 5123 | UnknownError 07:26:32 INFO - TEST-INFO | 5123: exit 0 07:26:32 INFO - Browser initialized. 07:26:32 INFO - Running cycle 1/1 for kraken test... 07:26:32 INFO - TEST-INFO | started process 5201 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm7C8dp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:26:44 INFO - PROCESS | 5201 | RSS: Main: 157417472 07:26:44 INFO - PROCESS | 5201 | 07:26:47 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50075/tests/kraken/driver.html?testName=audio-beat-detection) 07:26:48 INFO - PROCESS | 5201 | RSS: Main: 205254656 07:26:48 INFO - PROCESS | 5201 | 07:26:50 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50075/tests/kraken/driver.html?testName=audio-dft) 07:26:51 INFO - PROCESS | 5201 | RSS: Main: 196501504 07:26:51 INFO - PROCESS | 5201 | 07:26:54 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50075/tests/kraken/driver.html?testName=audio-fft) 07:26:55 INFO - PROCESS | 5201 | RSS: Main: 278208512 07:26:55 INFO - PROCESS | 5201 | 07:26:57 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50075/tests/kraken/driver.html?testName=audio-oscillator) 07:26:58 INFO - PROCESS | 5201 | RSS: Main: 187650048 07:26:58 INFO - PROCESS | 5201 | 07:27:00 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50075/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:27:00 INFO - PROCESS | 5201 | RSS: Main: 192442368 07:27:00 INFO - PROCESS | 5201 | 07:27:04 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50075/tests/kraken/driver.html?testName=imaging-darkroom) 07:27:04 INFO - PROCESS | 5201 | RSS: Main: 228638720 07:27:04 INFO - PROCESS | 5201 | 07:27:08 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50075/tests/kraken/driver.html?testName=imaging-desaturate) 07:27:08 INFO - PROCESS | 5201 | RSS: Main: 227758080 07:27:08 INFO - PROCESS | 5201 | 07:27:11 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50075/tests/kraken/driver.html?testName=json-parse-financial) 07:27:12 INFO - PROCESS | 5201 | RSS: Main: 227827712 07:27:12 INFO - PROCESS | 5201 | 07:27:14 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50075/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:27:14 INFO - PROCESS | 5201 | RSS: Main: 190083072 07:27:14 INFO - PROCESS | 5201 | 07:27:16 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:27:17 INFO - PROCESS | 5201 | RSS: Main: 198295552 07:27:17 INFO - PROCESS | 5201 | 07:27:19 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:27:20 INFO - PROCESS | 5201 | RSS: Main: 193875968 07:27:20 INFO - PROCESS | 5201 | 07:27:22 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:27:23 INFO - PROCESS | 5201 | RSS: Main: 190382080 07:27:23 INFO - PROCESS | 5201 | 07:27:26 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:27:27 INFO - PROCESS | 5201 | RSS: Main: 193138688 07:27:27 INFO - PROCESS | 5201 | 07:27:28 INFO - PROCESS | 5201 | Cycle 1(1): loaded http://localhost:50075/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50075/tests/kraken/driver.html?testName=ai-astar) 07:27:29 INFO - PROCESS | 5201 | RSS: Main: 244187136 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | __start_tp_report 07:27:29 INFO - PROCESS | 5201 | _x_x_mozilla_page_load 07:27:29 INFO - PROCESS | 5201 | _x_x_mozilla_page_load_details 07:27:29 INFO - PROCESS | 5201 | |i|pagename|runs| 07:27:29 INFO - PROCESS | 5201 | |0;ai-astar;106;113;113;115;109;113;113;113;113;114 07:27:29 INFO - PROCESS | 5201 | |1;audio-beat-detection;151;121;132;196;122;128;156;121;170;126 07:27:29 INFO - PROCESS | 5201 | |2;audio-dft;161;161;161;162;161;163;160;160;160;160 07:27:29 INFO - PROCESS | 5201 | |3;audio-fft;77;77;78;105;77;78;75;75;113;76 07:27:29 INFO - PROCESS | 5201 | |4;audio-oscillator;86;86;135;86;86;87;125;86;86;136 07:27:29 INFO - PROCESS | 5201 | |5;imaging-gaussian-blur;116;117;117;116;122;117;118;117;117;116 07:27:29 INFO - PROCESS | 5201 | |6;imaging-darkroom;146;146;146;146;147;147;146;146;146;146 07:27:29 INFO - PROCESS | 5201 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;94 07:27:29 INFO - PROCESS | 5201 | |8;json-parse-financial;67;66;68;65;66;69;67;66;68;66 07:27:29 INFO - PROCESS | 5201 | |9;json-stringify-tinderbox;65;66;65;67;66;65;66;66;67;66 07:27:29 INFO - PROCESS | 5201 | |10;stanford-crypto-aes;82;82;82;84;91;85;87;82;81;87 07:27:29 INFO - PROCESS | 5201 | |11;stanford-crypto-ccm;130;131;146;132;139;143;131;132;151;131 07:27:29 INFO - PROCESS | 5201 | |12;stanford-crypto-pbkdf2;174;171;169;169;169;172;173;169;169;169 07:27:29 INFO - PROCESS | 5201 | |13;stanford-crypto-sha256-iterative;67;68;67;66;67;68;66;72;67;66 07:27:29 INFO - PROCESS | 5201 | __end_tp_report 07:27:29 INFO - PROCESS | 5201 | __start_cc_report 07:27:29 INFO - PROCESS | 5201 | _x_x_mozilla_cycle_collect,1919 07:27:29 INFO - PROCESS | 5201 | __end_cc_report 07:27:29 INFO - PROCESS | 5201 | __startTimestamp1471530449074__endTimestamp 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | ------- Summary: start ------- 07:27:29 INFO - PROCESS | 5201 | Number of tests: 14 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:2.66 (2.4%) stddev-sans-first:1.62 07:27:29 INFO - PROCESS | 5201 | Values: 106.0 113.0 113.0 115.0 109.0 113.0 113.0 113.0 113.0 114.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:141.50 stddev:25.41 (18.0%) stddev-sans-first:26.75 07:27:29 INFO - PROCESS | 5201 | Values: 151.0 121.0 132.0 196.0 122.0 128.0 156.0 121.0 170.0 126.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 07:27:29 INFO - PROCESS | 5201 | Values: 161.0 161.0 161.0 162.0 161.0 163.0 160.0 160.0 160.0 160.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#3] audio-fft Cycles:10 Average:83.10 Median:77.00 stddev:13.82 (17.9%) stddev-sans-first:14.48 07:27:29 INFO - PROCESS | 5201 | Values: 77.0 77.0 78.0 105.0 77.0 78.0 75.0 75.0 113.0 76.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:86.00 stddev:22.34 (26.0%) stddev-sans-first:23.12 07:27:29 INFO - PROCESS | 5201 | Values: 86.0 86.0 135.0 86.0 86.0 87.0 125.0 86.0 86.0 136.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:1.77 (1.5%) stddev-sans-first:1.81 07:27:29 INFO - PROCESS | 5201 | Values: 116.0 117.0 117.0 116.0 122.0 117.0 118.0 117.0 117.0 116.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 07:27:29 INFO - PROCESS | 5201 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:27:29 INFO - PROCESS | 5201 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 07:27:29 INFO - PROCESS | 5201 | Values: 67.0 66.0 68.0 65.0 66.0 69.0 67.0 66.0 68.0 66.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 07:27:29 INFO - PROCESS | 5201 | Values: 65.0 66.0 65.0 67.0 66.0 65.0 66.0 66.0 67.0 66.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:3.20 (3.8%) stddev-sans-first:3.28 07:27:29 INFO - PROCESS | 5201 | Values: 82.0 82.0 82.0 84.0 91.0 85.0 87.0 82.0 81.0 87.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:135.50 stddev:7.62 (5.6%) stddev-sans-first:7.70 07:27:29 INFO - PROCESS | 5201 | Values: 130.0 131.0 146.0 132.0 139.0 143.0 131.0 132.0 151.0 131.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:170.00 stddev:1.96 (1.2%) stddev-sans-first:1.58 07:27:29 INFO - PROCESS | 5201 | Values: 174.0 171.0 169.0 169.0 169.0 172.0 173.0 169.0 169.0 169.0 07:27:29 INFO - PROCESS | 5201 | 07:27:29 INFO - PROCESS | 5201 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.78 (2.7%) stddev-sans-first:1.88 07:27:29 INFO - PROCESS | 5201 | Values: 67.0 68.0 67.0 66.0 67.0 68.0 66.0 72.0 67.0 66.0 07:27:29 INFO - PROCESS | 5201 | -------- Summary: end -------- 07:27:29 INFO - PROCESS | 5201 | 07:27:30 INFO - TEST-INFO | 5201: exit 0 07:27:30 INFO - TEST-OK | kraken | took 63709ms 07:27:30 INFO - SUITE-END | took 1184s 07:27:30 INFO - Completed test suite (00:19:44) 07:27:30 INFO - javascript benchmark 07:27:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2584.9353766155846, 2610.0995156670965, 2610.634511168437, 2633.3416645838543, 2634.4467661791023, 4500.1674690182335, 4549.181147393468, 4668.299755036742, 4708.340832283478, 4713.929291060636, 4593.058423023281, 4616.676832621718, 4620.653450991173, 4649.070185962804, 4698.577128058474, 4237.343211802176, 4241.618254357108, 4315.71947823392, 4363.934540981872, 4411.250087485135, 4328.935065973998, 4353.978230108845, 4394.868153955386, 4401.141777353418, 4412.911741765162, 4203.91592168155, 4255.234057869582, 4323.632491238217, 4332.176886391576, 4400.229959757045, 4187.057911969813, 4210.6841986851105, 4217.346311321719, 4243.681723870722, 4255.659547236244, 4219.2194444027955, 4297.634701050414, 4300.849470268557, 4325.243082460557, 4362.694611377205, 4510.368548403226, 4665.2535594304745, 4669.089527542131, 4700.764961751899, 4709.929351059737, 4927.556519913189, 4968.602511799084, 4973.079980203674, 4985.426675932278, 5010.624203184776, 5041.495850414965, 5058.038972595232, 5066.392032956032, 5086.1353569892835, 5098.311727916758, 4530.524294949048, 4599.1721490132095, 4761.547652972928, 4761.928571071437, 4765.332853400527, 5054.7472626368535, 5130.230465430215, 5157.871053223625, 5176.249443830623, 5177.404598471224, 4916.975415122902, 4951.133551628451, 4953.554180123806, 4959.057779021974, 4960.206366981266, 4997.650164488524, 5001.649884508122, 5038.3198268234055, 5041.899162016739, 5043.268726034705, 4827.179379505493, 4879.926801097987, 4941.703497790144, 4947.727874966911, 5031.52200540948, 4950.331705219821, 4982.0, 4982.3522942018035, 5005.224190250517, 5022.824201153016, 4250.3836943643, 4255.5744425557195, 4261.74429534229, 4276.294411422143, 4287.12114016632, 3642.581103173116, 3667.339878821784, 3679.8712045078296, 3721.702263818888, 3786.450964610116, 4300.88177073963, 4336.566343365701, 4337.60961513459, 4371.846985355561, 4373.497047839475, 4990.6506544541535, 5025.723585202849, 5027.748612569357, 5034.84895453137, 5053.570446512088, 4210.6841986851105, 4258.850940217139, 4339.7830108494445, 4342.522322544518, 4374.5625437456, 4482.641388688897, 4497.482789479186, 4515.68390212682, 4550.635949124062, 4552.203364411214, 4712.95287047132, 4714.528547145258, 4717.693349932243, 4718.551737584924, 4753.572178503882, 4881.0, 4947.851564453073, 4992.6255530834605, 4996.575291100297, 5005.074061298671, 4712.69367491112, 4740.146773580788, 4770.427548694179, 4806.519348065305, 4811.518848115161, 4948.529889660621, 4951.480094590161, 4954.727489987941, 4966.553010229097, 4995.325631039908, 4829.203181475017, 4857.1742803724155, 5007.8748031298055, 5025.874353141201, 5073.086844368049, 4837.129316723023, 4837.612990960644, 4859.635527335393, 4894.265860120868, 4896.4124305083615, 4801.303810947463, 4821.9035619286715, 4824.541668541483, 4826.686265392738, 4863.392075990501, 4661.953380466288, 4755.690880092783, 4776.402949631296, 4800.0, 4829.517048295143, 4882.511748825089, 4890.0, 4933.654644175017, 4993.475685053162, 5000.949990500194, 4816.34979277793, 4828.37231159947, 4829.082474329866, 4833.0, 4878.243872199815], "value": 4592.635638676597, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.777396815242, 578.7684926029589, 579.2064871126557, 580.518169918967, 597.8176656119887, 28883.27791805206, 31040.53439198411, 31084.0, 42166.57833421665, 44024.496937882766, 40328.79835600825, 40624.59375406245, 40659.0, 40724.59275407245, 40757.79621101898, 7608.12506561747, 7748.108967468757, 7751.379889608846, 7758.185390533998, 7758.456908016441, 7615.124260710034, 7696.153423123452, 7703.537787732754, 7703.614819259043, 7705.73029943952, 7654.655540500691, 7697.961510192441, 7698.230176982313, 7700.306972372486, 7710.344620707247, 7650.234976502361, 7651.617419129049, 7673.654685539164, 7685.03937007874, 7695.307422331961, 7622.856571514317, 7687.308142267167, 7688.115866675348, 7695.846083078363, 7716.382689384835, 38664.80667596672, 39035.0, 39061.41407878884, 39062.21875562473, 39084.0, 3119.5632611434417, 3360.4455264881267, 3458.058083011342, 3707.0917625181733, 3793.2603142387247, 28523.714762852313, 28758.71241287581, 28891.711082889113, 28969.0, 30263.69736302631, 3022.863971121305, 3049.7102775236317, 3117.06488053583, 3122.0945925681526, 3140.6388265349547, 257521.0, 257839.0, 258358.0, 258521.0, 261136.69431652912, 50721.74639126855, 51137.744311278206, 51155.744221278655, 51341.0, 51475.48524514707, 32963.0, 33712.831435842665, 33771.15572110717, 35296.470552941726, 55239.990201420565, 13835.861641383659, 13840.30798460073, 13850.238236896867, 13903.582892513241, 13914.0, 10322.948385258176, 10388.220883433773, 10422.5830966762, 10437.478126093665, 10470.16238700902, 17961.820381796275, 17968.0, 17986.37047703324, 18015.18931648079, 18243.0, 6568.868622627616, 6814.250432452426, 6875.518713690094, 6907.274736152732, 6952.270011648805, 753.4800987318732, 759.5822297736223, 818.0265484073933, 862.3661608717588, 910.0535443139062, 26797.598036029474, 27266.591001135, 27447.451050979267, 27726.0, 28194.85902570474, 769.830637259802, 942.0579420579421, 950.6815216902415, 955.6798472511647, 1002.5388321372104, 13363.93318033423, 14584.343704533321, 14685.706285874223, 14968.17675027862, 15010.699786004438, 401813.0, 403728.0, 403767.0, 404422.0, 404509.0, 46194.5380546197, 47655.28517072247, 47979.76010119997, 48033.27950080752, 48130.0, 1053.0838170791308, 1105.1048650592884, 1128.6388355726253, 1145.255583870474, 1151.4875880233383, 27697.0, 28775.85612071926, 29389.26526836846, 29474.557881632223, 29966.25084372908, 41581.37627935644, 42185.0, 42194.78902605467, 42234.5776542243, 42236.155276893674, 252417.0, 254173.0, 254273.0, 254591.0, 394705.0, 36153.45769813402, 36275.63724362723, 36455.817720911225, 36462.4530632046, 37163.81418092892, 41050.79474602608, 41896.3715544261, 41927.790361048, 42293.15413691771, 42439.78780106203, 36437.71653110231, 36493.08767280839, 36809.07977300589, 36879.0, 37055.62944370522, 35522.289554208255, 35907.84088479124, 35951.28097438089, 36888.81555592205, 37538.624613754604, 35622.28755424929, 35699.28601427905, 35814.4627830588, 36150.63849361578, 36730.8163459181, 30451.847740761154, 33447.33105337928, 33529.16177095592, 33532.32935341328, 33703.15742106467, 24975.375615609028, 25252.87373563194, 25967.220983370527, 26107.0, 26423.33941651474], "value": 17267.964916840025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.43833394066746, 351.58688540964374, 359.86684926577163, 363.99454008189883, 381.5192857000181, 12705.0, 13348.93325533371, 13656.931715341421, 17278.0, 17578.472645820613, 15512.379504819794, 16396.590085247874, 16439.506814795575, 16513.83486165138, 16551.668966620662, 4901.754912254391, 4993.425756038047, 5110.233464980245, 5133.717645529495, 5138.897222055557, 3711.0907827582146, 5011.699298042111, 5082.440931497532, 5083.288339632454, 5728.68492232927, 6030.758769649209, 6079.696015199244, 6117.5717699761035, 6119.0209566469375, 6122.969385153068, 5868.677222752725, 5938.703064846741, 5967.6419414835245, 5970.164177015221, 6097.14639950405, 5220.29526013987, 5223.712695801767, 5225.581953443715, 5226.55574276187, 5230.058589453897, 19952.0, 20633.793662063337, 20650.0, 20690.896545517175, 20820.47948801277, 1798.3615816385256, 1854.1470923375264, 1867.8692491525599, 1930.6138772245606, 2372.5254949010096, 12388.690282742913, 12714.173578717353, 13087.86912130876, 13298.202107873558, 13528.594142175652, 1592.904425734448, 1655.1062426289789, 1671.4901954903748, 1688.0293831047074, 1695.8728095392771, 68457.0, 68501.65749171271, 69362.65318673424, 70121.64939175322, 70175.64912175457, 9242.630294788134, 9367.62529498826, 9436.0, 9612.231021518262, 9690.70927872165, 12981.350932453528, 12998.220106793622, 13018.0, 13023.739525209443, 13033.348332583333, 6644.0, 6750.088738020305, 6835.658217089126, 6854.34883686049, 6924.826879327957, 6112.969435152885, 6126.846828829226, 6177.907331390033, 6269.7492100316385, 6413.871722565523, 5937.910931336033, 5970.283565972111, 5999.310079340845, 6050.788222412194, 6056.515478761688, 1735.9305627775, 1740.8868423552428, 1758.2088060372882, 1759.6304775997185, 1783.4025601423668, 761.9256847844623, 780.3406121827047, 882.8611091691646, 904.7059705595708, 975.6243846119295, 16572.254248558846, 16645.834791564474, 17044.0, 17114.657706845792, 17564.0, 802.3982013489882, 819.3649921311031, 842.1073661918387, 865.8960924688952, 907.7957459571544, 9445.291603129794, 9954.502274886227, 10030.297879148535, 10051.0, 10065.094141527297, 375605.0, 383832.0, 386617.0, 387911.0, 389590.0, 47803.76098119557, 62014.0, 65348.0, 65999.0, 70586.294137059, 875.1685898396548, 901.544719916438, 917.788908551037, 942.9622815087457, 945.1588086602928, 14398.712025759216, 14859.419793017152, 16331.510054698378, 16390.262438190548, 16573.585660358105, 34054.8297258522, 36017.81991090133, 36062.819685901406, 36499.81750091232, 37222.81388593148, 42916.0, 43136.92385615383, 43203.0, 45424.318635221156, 45467.772661136485, 4951.653384263067, 7059.764541205372, 7460.29127232912, 7514.549127052176, 7534.133574638987, 11220.0, 11301.491432885705, 11413.144014199135, 11754.471048802654, 11809.0, 9570.61717531291, 9729.270304727315, 9753.707388778348, 9857.802843942938, 10177.84733229017, 9037.50293733851, 9789.755256118655, 9883.802323953625, 9930.106290433896, 9943.25425593069, 9396.718098457057, 9480.478573678238, 9882.061204185591, 10162.491875406347, 10345.948270258601, 7411.592362420119, 7574.356179724785, 7704.6918123274445, 7706.807329816799, 7809.297163255334, 8995.955020224857, 9454.905450945402, 9496.952515237379, 9527.952360238154, 10112.443815590213], "value": 8098.272246158667, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.8850897673694, 1385.2450414524085, 1442.798008278841, 1638.565780068283, 1651.2569343795287, 7313.305236002585, 7895.881561776578, 7898.210178982099, 7905.683772649088, 7919.604019799009, 6786.830329241771, 6974.8256293592685, 6982.615956122411, 7160.462965277599, 7882.448228623999, 1861.934832280874, 2139.411661793004, 2204.173434961889, 2205.0297868937696, 2223.299660606912, 2153.9892300538477, 2155.568886222754, 2158.56828634273, 2159.114762947192, 2160.967585486219, 2104.8000439958255, 2182.301663467691, 2184.8142907852853, 2186.6719992001167, 2188.890555472228, 2105.6420408530585, 2109.1247132440094, 2114.9577008459823, 2146.1415433826437, 2148.022649694385, 2058.8249998750125, 2061.3815855243365, 2062.669972804359, 2066.442060643633, 2067.0388269454625, 7584.696612135508, 7602.163761986177, 7638.732644357421, 7925.643346049442, 8008.239217274349, 4403.141387429452, 4515.571020753023, 4517.073999830027, 4520.909581808378, 4526.592606665416, 2716.348076461635, 2841.3748975225417, 2869.7130286971346, 2876.913692589226, 2887.5091234490187, 930.3394589841221, 934.6541779541545, 939.8543225800012, 942.4533770413144, 954.3605784124653, 10609.73475663107, 10616.946915265375, 10619.681409557727, 10629.787404251949, 10631.787364252748, 4129.359949207877, 4178.519470260898, 4203.138356636943, 4207.97896010518, 4275.29457639486, 9097.226735727405, 9102.90897091034, 9135.588898499584, 9146.771330716652, 9153.908460915438, 4391.956080439155, 4392.538783427758, 4393.956060439418, 4397.9560204398185, 4400.581944715247, 3907.6092390760696, 3968.6229808168177, 3990.3216453203027, 3992.041909941593, 3996.9600303997167, 3279.983600081984, 3496.7727097738566, 3586.802725850051, 3634.945475817865, 3668.944965825515, 1197.6047904191596, 1200.393801130441, 1205.4997176171967, 1207.5290636651712, 1208.3173007250875, 846.9165852004016, 847.563504795031, 856.5631527920805, 872.5942436766934, 889.768660148366, 5294.0, 5463.590230732711, 5498.3127109111365, 5553.722313884289, 5564.693941833238, 846.9407141500159, 868.3313848336871, 868.3617541107408, 872.5070335260557, 921.4056933277998, 3731.3470142724914, 3789.6589306961955, 3799.848006079781, 3808.980955095207, 3834.2714884172474, 9060.0, 9445.952770236105, 9447.811043779086, 9484.478353690612, 9492.430454172772, 9333.673321433716, 9344.159025687584, 9350.67272645454, 9358.0, 9383.906160938439, 7148.535345202649, 7195.532290401212, 7225.927740722525, 7227.241139680258, 7235.0, 5156.535911767809, 5208.453112423293, 5227.451117632746, 5308.336457942757, 5309.65487243328, 8476.23713865755, 8619.741407757778, 8643.222110010129, 8725.91274087251, 8764.255038321558, 8650.56747162652, 8859.734207973772, 8895.021547629625, 8896.421732587361, 8911.55442227899, 6514.413702766774, 6727.428168605723, 6782.355676210949, 6828.9658551706925, 6843.692033858389, 6480.287168411565, 6929.792106236821, 7095.538789978635, 7168.605726685082, 7349.779506614596, 6511.609303441809, 6539.934600653933, 6570.5729127606555, 6584.736610535525, 6611.504137189634, 6438.8390290243115, 6441.066045423481, 6456.806295810945, 6490.448311893542, 6525.086487891794, 6059.394060594082, 6133.080037994337, 6141.907871382022, 6144.17053697763, 6149.139120523219, 4527.4340707411575, 4538.2511885538515, 4555.681102322938, 4617.653675974382, 4699.718016918996, 4362.127574485179, 4415.381846541551, 4473.418455600751, 4487.596116349544, 4590.862274131781], "value": 4087.1060637830324, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.35195466630745, 284.37295762842933, 286.95405247871497, 287.7870375921818, 297.7246047406149, 4915.70505769654, 4956.794226569178, 4964.8014079436825, 4968.552830245278, 4991.625628077895, 4931.65478416511, 4932.235503496955, 4936.605071594273, 4937.136001199785, 4942.851714448563, 3632.4551317302353, 3724.9255014899686, 3765.548134223897, 3776.8678096266635, 3854.8265328060165, 3815.866444674437, 3816.503854498911, 3828.3874580067195, 3840.404737265714, 3842.4812650292274, 3807.1433927366397, 3816.618338166189, 3819.713521485886, 3831.2337532493475, 3842.654161125498, 3623.8188090595363, 3653.4702468142104, 3784.829682664259, 3786.2616789726017, 3786.848526058954, 3361.8151001694896, 3371.123507887942, 3373.245912125681, 3378.4932260160926, 3381.7294616430627, 4713.787879545429, 4770.976145119287, 4796.112719146969, 4829.396325459317, 4861.465238823726, 4128.979355103235, 4304.837693822682, 4384.912301753947, 4427.557244275579, 4432.22436073689, 5106.523336181949, 5108.157154069574, 5110.5911527078115, 5114.539691427753, 5129.307543481619, 3652.8356223969986, 3665.9450108248398, 3668.7798732075894, 3677.1174918019483, 3684.1158122050783, 5249.842504724865, 5370.3287089113865, 5382.354117505885, 5442.836714898559, 5463.2078348639225, 3649.489071529988, 3669.2478042001326, 3673.5856695172015, 3677.2277821657754, 3693.3351996640326, 3744.7718607428537, 3747.512823333004, 3749.1564398010228, 3752.7373083884413, 3753.7560058596105, 4064.898377540585, 4258.808353624095, 4292.5063617683745, 4315.136972605492, 4324.17840610283, 5052.595792336604, 5053.6898331592865, 5060.84817455477, 5071.822486212965, 5077.822276220315, 1271.9809202861964, 1297.8442586889635, 1304.3282709404664, 1304.439091190778, 1310.8164856920039, 812.9512229266263, 827.5779352530133, 830.5058490198322, 833.1709948601059, 896.7713233125642, 4419.895026243439, 4462.41988541494, 4469.262571675637, 4481.708688935209, 4484.887877803081, 5227.536289838851, 5231.273679685694, 5232.92150617741, 5241.318628578337, 5241.375173696167, 4293.484781826201, 4481.820727170942, 4487.865364039084, 4490.528494508095, 4491.932621010687, 2773.278947473631, 2780.527310357244, 2785.206216228365, 2792.9860350698523, 2793.580962855547, 4876.366072410635, 4882.6826256293225, 4927.0, 4936.827211047596, 4940.308356830046, 5119.87200319995, 5348.465153484698, 5349.170878513836, 5359.866003349947, 5382.569394448513, 4876.366072410635, 4879.829205977845, 4882.292067650242, 4887.926681099858, 4909.165441874889, 5130.153524668537, 5170.784865556667, 5181.922271166011, 5183.974080129575, 5198.818041368534, 4949.0, 5036.370453693288, 5105.7191854448365, 5112.721819545114, 5132.409772876167, 4882.511748825089, 4886.609071274361, 4894.412670479423, 4904.768330409726, 4906.779194936308, 3810.1236715555024, 3820.446035324807, 3843.577206507338, 3843.769373837467, 3844.596317386634, 5047.772850221822, 5175.0, 5279.47205279469, 5281.313429254173, 5284.550813180923, 4974.527419895104, 4978.626603004862, 4992.226204938241, 5004.224345226496, 5038.571721403721, 4958.45456999737, 5021.44764075944, 5054.873628159326, 5057.266696329085, 5070.391553013662, 4781.211100168433, 4961.668016032459, 4975.5522003019905, 4989.600831933364, 5031.3207716959205, 4945.0, 5026.673266237683, 5171.715555644476, 5172.844814655567, 5177.223416487557, 5060.3168572242275, 5069.949300506948, 5094.872628184325, 5096.184610462308, 5101.362329708786], "value": 3831.987046088863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.9587926784759, 663.1147418196707, 687.2955220898575, 717.6196615793627, 738.1253214940297, 11899.821502677456, 12872.935635321834, 12884.484620615163, 12898.871011289884, 12905.29020903851, 12475.18911270769, 12531.561395351164, 12551.748965020694, 12554.183978041421, 12574.68563285916, 609.7256234694405, 737.1743647115238, 742.9257074292582, 768.2739810878721, 772.1622040086513, 768.5196752029981, 770.306723948448, 771.0439055571079, 771.1209221487509, 772.1583474013324, 802.8514724775906, 803.220875750524, 803.2931020701803, 803.3813963248296, 803.7628899474669, 643.4852118305361, 657.6382989355883, 661.0348890619701, 662.9867402651968, 801.1027649033091, 573.2404563952779, 586.6069733278714, 587.2395248153651, 592.0586267834165, 596.9880602387972, 11147.386893720843, 11192.38441885696, 11206.663800086011, 11221.887781122248, 11245.381504017276, 12043.397830108546, 12357.752844943141, 12374.257544547356, 12472.81290780639, 12900.0, 10491.58033678652, 11049.895010498965, 11253.156013299036, 11378.431078446045, 11659.358735269558, 3853.6724378427866, 3880.0881792778673, 3911.119998000456, 4099.282625540548, 4137.8758637240935, 65820.3417965819, 65843.0, 67514.3248567513, 68908.3109168907, 69072.65463672699, 9304.348695591243, 9530.714078577654, 10075.647352342632, 10341.793164136676, 10395.896041039645, 18302.908485457487, 18568.164432600566, 18569.814301856808, 18573.628527429377, 18610.906945465187, 6309.08518264849, 6310.716017779119, 6351.682415879188, 6503.317151699098, 6618.371254730793, 3488.337215928965, 3493.161641206092, 3512.2799826035603, 3541.8937431877084, 3634.8727794527595, 2772.7227277272113, 2848.145556333092, 2885.4373397187346, 2886.3794284228993, 2893.667228268734, 479.47976445556264, 480.9975950120297, 495.44015262752856, 497.0407114269495, 499.1090902738667, 495.6753326571102, 511.5217271850757, 520.828126718182, 528.6827903258014, 552.9466366571687, 6882.186550803577, 7393.371563417062, 7522.322990930843, 8480.194381533744, 8560.272376847914, 455.22384334709244, 531.3252169744403, 570.2842932120162, 572.6306532286652, 586.9236999190078, 3529.8411571479346, 3861.575226725058, 3863.150106976461, 3999.6600288975183, 4021.195760847842, 93786.53106734423, 94048.0, 95957.5202123985, 96080.51959740157, 99598.03215742724, 78784.0, 80825.59587202026, 81244.18755812367, 81864.59067704623, 83459.5827020861, 70475.0, 70653.2934670657, 72629.6368518154, 74102.2589774117, 76426.61786691031, 7524.661390237343, 7560.6219689016425, 7593.392528597588, 7774.494657847222, 7893.486923349964, 42308.57691423169, 42380.57619423766, 42877.0, 42886.0, 43210.78394608007, 53281.20078198753, 55122.72438637781, 57871.71064144652, 59488.0, 59774.0, 10554.472276386303, 10738.355698658106, 10891.0, 10957.342559446459, 11159.832602511131, 12103.697407564881, 12245.816312755138, 12249.510019599116, 12403.62789116328, 12840.0, 10282.228832837716, 10817.567297308335, 11052.0, 11141.108711302913, 11144.832827507757, 10870.728231794268, 11406.258593191416, 11813.645590632295, 11856.347900865536, 11861.169718119994, 10067.597296108366, 10742.19433542503, 10879.075278601407, 10904.454777261264, 10905.291156074829, 8734.868976965223, 8738.868916966123, 9141.817163656557, 9411.623535058521, 9486.288528360263, 8101.959490202511, 8951.865722014045, 8983.640654373752, 9034.683786067457, 9120.863187052068], "value": 6312.357165029348, "unit": "score"}], "name": "dromaeo_css", "value": 6316.299287752061, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 113.0, 113.0, 115.0, 109.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 132.0, 196.0, 122.0, 128.0, 156.0, 121.0, 170.0, 126.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 162.0, 161.0, 163.0, 160.0, 160.0, 160.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 78.0, 105.0, 77.0, 78.0, 75.0, 75.0, 113.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 135.0, 86.0, 86.0, 87.0, 125.0, 86.0, 86.0, 136.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 122.0, 117.0, 118.0, 117.0, 117.0, 116.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 65.0, 66.0, 69.0, 67.0, 66.0, 68.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 67.0, 66.0, 65.0, 66.0, 66.0, 67.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 84.0, 91.0, 85.0, 87.0, 82.0, 81.0, 87.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 146.0, 132.0, 139.0, 143.0, 131.0, 132.0, 151.0, 131.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 169.0, 169.0, 169.0, 172.0, 173.0, 169.0, 169.0, 169.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 67.0, 68.0, 66.0, 72.0, 67.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1548.1, "alertThreshold": 2.0}]} 07:27:30 INFO - Return code: 0 07:27:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:27:30 INFO - # TBPL SUCCESS # 07:27:30 INFO - Running post-action listener: _resource_record_post_action 07:27:30 INFO - [mozharness: 2016-08-18 14:27:30.880276Z] Finished run-tests step (success) 07:27:30 INFO - Running post-run listener: _resource_record_post_run 07:27:30 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 397561856; Read time: 0; Write time: 333944 07:27:30 INFO - TinderboxPrint: CPU usage
13.0% 07:27:30 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 07:27:30 INFO - TinderboxPrint: I/O write bytes / time
397,561,856 / 333,944 07:27:30 INFO - TinderboxPrint: CPU idle
8,341.4 (87.0%) 07:27:30 INFO - TinderboxPrint: CPU user
1,201.1 (12.5%) 07:27:30 INFO - TinderboxPrint: Swap in / out
0 / 0 07:27:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 113537024; Read time: 0; Write time: 89812 07:27:30 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 284024832; Read time: 0; Write time: 244132 07:27:31 INFO - Running post-run listener: _upload_blobber_files 07:27:31 INFO - Blob upload gear active. 07:27:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:27:31 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 07:27:31 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'] 07:27:31 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 07:27:32 INFO - (blobuploader) - INFO - Open directory for files ... 07:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:36 INFO - (blobuploader) - INFO - Done attempting. 07:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:37 INFO - (blobuploader) - INFO - Done attempting. 07:27:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:27:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:37 INFO - (blobuploader) - INFO - Done attempting. 07:27:37 INFO - (blobuploader) - INFO - Iteration through files over. 07:27:37 INFO - Return code: 0 07:27:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:27:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:27:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fae336ac8e78575ae61ec00be0b04261492df0fb2e9392e07c33a5c5b23adb3e9902842483ba0a89d802be18e54ac4ca301cfe29f38ac39551c97693e49426bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d247c82332b882944bd80904089be3cd1f1b17a5796908062559c55915c6a2e7baf8fbf2a97ad55b8d2c846f4bc7b7664e8ef8629cace6f401d07c35495f6e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cf5ac26046e7b61ebe6f280524b1813f119811ca5460ac44e7bff9b7a40fe04031c4847c66f64906141df39da7974064903f3d56721e936142d7986788ed0b88"} 07:27:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:27:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:27:37 INFO - Contents: 07:27:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fae336ac8e78575ae61ec00be0b04261492df0fb2e9392e07c33a5c5b23adb3e9902842483ba0a89d802be18e54ac4ca301cfe29f38ac39551c97693e49426bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d247c82332b882944bd80904089be3cd1f1b17a5796908062559c55915c6a2e7baf8fbf2a97ad55b8d2c846f4bc7b7664e8ef8629cace6f401d07c35495f6e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cf5ac26046e7b61ebe6f280524b1813f119811ca5460ac44e7bff9b7a40fe04031c4847c66f64906141df39da7974064903f3d56721e936142d7986788ed0b88"} 07:27:37 INFO - Running post-run listener: copy_logs_to_upload_dir 07:27:37 INFO - Copying logs to upload dir... 07:27:37 INFO - mkdir: /builds/slave/test/build/upload/logs 07:27:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.894379 ========= master_lag: 1.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-18 07:27:39.204717) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 07:27:39.212441) ========= 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=32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658 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/fae336ac8e78575ae61ec00be0b04261492df0fb2e9392e07c33a5c5b23adb3e9902842483ba0a89d802be18e54ac4ca301cfe29f38ac39551c97693e49426bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d247c82332b882944bd80904089be3cd1f1b17a5796908062559c55915c6a2e7baf8fbf2a97ad55b8d2c846f4bc7b7664e8ef8629cace6f401d07c35495f6e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cf5ac26046e7b61ebe6f280524b1813f119811ca5460ac44e7bff9b7a40fe04031c4847c66f64906141df39da7974064903f3d56721e936142d7986788ed0b88"} build_url:https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/bXqWcbpxT4e9AIIxfLPFTA/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/fae336ac8e78575ae61ec00be0b04261492df0fb2e9392e07c33a5c5b23adb3e9902842483ba0a89d802be18e54ac4ca301cfe29f38ac39551c97693e49426bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d247c82332b882944bd80904089be3cd1f1b17a5796908062559c55915c6a2e7baf8fbf2a97ad55b8d2c846f4bc7b7664e8ef8629cace6f401d07c35495f6e1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cf5ac26046e7b61ebe6f280524b1813f119811ca5460ac44e7bff9b7a40fe04031c4847c66f64906141df39da7974064903f3d56721e936142d7986788ed0b88"}' ========= master_lag: 0.51 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 07:27:39.737844) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 07:27:39.738264) ========= 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=32c5991c409366f7fec8a718000001d2-1471529208.945450-770689658 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 07:27:39.755583) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 07:27:39.755957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 07:27:39.756273) ========= ========= Total master_lag: 2.12 =========