builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1472782024.88 results: success (0) buildid: 20160901174542 builduid: eae1db59bb6a4ca7ac9b3e2e285e6f88 revision: 2d457db23476fecab9e6e66a737c6723d0d2767c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:04.882365) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:04.882868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:04.883200) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:04.926309) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:04.926636) ========= 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=df17445c3590ff7897617a0e00000225-1472780962.49334-283195181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 19:07:05-- 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% 21.2M=0.001s 2016-09-01 19:07:05 (21.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.020056) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.020379) ========= 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=df17445c3590ff7897617a0e00000225-1472780962.49334-283195181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015271 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.070820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.071203) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d457db23476fecab9e6e66a737c6723d0d2767c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2d457db23476fecab9e6e66a737c6723d0d2767c --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=df17445c3590ff7897617a0e00000225-1472780962.49334-283195181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 19:07:05,434 truncating revision to first 12 chars 2016-09-01 19:07:05,434 Setting DEBUG logging. 2016-09-01 19:07:05,434 attempt 1/10 2016-09-01 19:07:05,434 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d457db23476?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 19:07:05,729 unpacking tar archive at: mozilla-central-2d457db23476/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628798 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.714749) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.715169) ========= script_repo_revision: 2d457db23476fecab9e6e66a737c6723d0d2767c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.715627) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.715937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 19:07:05.725537) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-09-01 19:07:05.725856) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=df17445c3590ff7897617a0e00000225-1472780962.49334-283195181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:07:06 INFO - MultiFileLogger online at 20160901 19:07:06 in /builds/slave/test 19:07:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:07:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:06 INFO - {'append_to_log': False, 19:07:06 INFO - 'base_work_dir': '/builds/slave/test', 19:07:06 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:07:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:06 INFO - 'branch': 'Firefox-Non-PGO', 19:07:06 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:06 INFO - 'config_files': ('talos/linux_config.py',), 19:07:06 INFO - 'default_actions': ('clobber', 19:07:06 INFO - 'read-buildbot-config', 19:07:06 INFO - 'download-and-extract', 19:07:06 INFO - 'populate-webroot', 19:07:06 INFO - 'create-virtualenv', 19:07:06 INFO - 'install', 19:07:06 INFO - 'run-tests'), 19:07:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:06 INFO - 'download_minidump_stackwalk': True, 19:07:06 INFO - 'download_symbols': 'ondemand', 19:07:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:06 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:06 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:06 INFO - 'installer_path': 'installer.exe', 19:07:06 INFO - 'log_level': 'info', 19:07:06 INFO - 'log_name': 'talos', 19:07:06 INFO - 'log_to_console': True, 19:07:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:07:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:07:06 INFO - 'opt_config_files': (), 19:07:06 INFO - 'pip_index': False, 19:07:06 INFO - 'sps_profile': False, 19:07:06 INFO - 'sps_profile_interval': 0, 19:07:06 INFO - 'suite': 'dromaeojs', 19:07:06 INFO - 'system_bits': '32', 19:07:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:06 INFO - 'title': 'talos-linux64-ix-021', 19:07:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:06 INFO - 'use_talos_json': True, 19:07:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:06 INFO - 'work_dir': 'build'} 19:07:06 INFO - [mozharness: 2016-09-02 02:07:06.185327Z] Running clobber step. 19:07:06 INFO - Running pre-action listener: _resource_record_pre_action 19:07:06 INFO - Running main action method: clobber 19:07:06 INFO - rmtree: /builds/slave/test/build 19:07:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:07 INFO - Running post-action listener: _resource_record_post_action 19:07:07 INFO - [mozharness: 2016-09-02 02:07:07.170800Z] Finished clobber step (success) 19:07:07 INFO - [mozharness: 2016-09-02 02:07:07.170901Z] Running read-buildbot-config step. 19:07:07 INFO - Running pre-action listener: _resource_record_pre_action 19:07:07 INFO - Running main action method: read_buildbot_config 19:07:07 INFO - Using buildbot properties: 19:07:07 INFO - { 19:07:07 INFO - "project": "", 19:07:07 INFO - "product": "firefox", 19:07:07 INFO - "script_repo_revision": "production", 19:07:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:07:07 INFO - "repository": "", 19:07:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 19:07:07 INFO - "buildid": "20160901174542", 19:07:07 INFO - "pgo_build": "False", 19:07:07 INFO - "basedir": "/builds/slave/test", 19:07:07 INFO - "buildnumber": 202, 19:07:07 INFO - "slavename": "talos-linux64-ix-021", 19:07:07 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:07:07 INFO - "platform": "ubuntu64_hw", 19:07:07 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 19:07:07 INFO - "branch": "mozilla-central", 19:07:07 INFO - "repo_path": "mozilla-central", 19:07:07 INFO - "revision": "2d457db23476fecab9e6e66a737c6723d0d2767c", 19:07:07 INFO - "stage_platform": "linux64", 19:07:07 INFO - "builduid": "eae1db59bb6a4ca7ac9b3e2e285e6f88", 19:07:07 INFO - "slavebuilddir": "test" 19:07:07 INFO - } 19:07:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 19:07:07 INFO - Running post-action listener: _resource_record_post_action 19:07:07 INFO - [mozharness: 2016-09-02 02:07:07.173360Z] Finished read-buildbot-config step (success) 19:07:07 INFO - [mozharness: 2016-09-02 02:07:07.173452Z] Running download-and-extract step. 19:07:07 INFO - Running pre-action listener: _resource_record_pre_action 19:07:07 INFO - Running main action method: download_and_extract 19:07:07 INFO - mkdir: /builds/slave/test/build/tests 19:07:07 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')]} 19:07:07 INFO - trying https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:07:07 INFO - Downloading https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:07 INFO - Downloaded 1361 bytes. 19:07:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 19:07:07 INFO - Using the following test package requirements: 19:07:07 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:07:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:07:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:07:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'jsshell-linux-x86_64.zip'], 19:07:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:07:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 19:07:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:07:07 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:07:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:07:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:07 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:07:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:07:07 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')]} 19:07:07 INFO - trying https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:07:07 INFO - Downloading https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:08 INFO - Downloaded 20334479 bytes. 19:07:08 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 19:07:13 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 19: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')]} 19:07:13 INFO - trying https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 19:07:13 INFO - Downloading https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:14 INFO - Downloaded 20334479 bytes. 19:07:14 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 19: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')]} 19:07:18 INFO - trying https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 19:07:18 INFO - Downloading https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:19 INFO - Downloaded 14413998 bytes. 19:07:19 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 19:07:19 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')]} 19:07:19 INFO - trying https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:07:19 INFO - Downloading https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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 19:07:21 INFO - Downloaded 55861961 bytes. 19:07:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:07:21 INFO - mkdir: /builds/slave/test/properties 19:07:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:07:21 INFO - Writing to file /builds/slave/test/properties/build_url 19:07:21 INFO - Contents: 19:07:21 INFO - build_url:https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 19:07:21 INFO - Running post-action listener: _resource_record_post_action 19:07:21 INFO - Running post-action listener: set_extra_try_arguments 19:07:21 INFO - [mozharness: 2016-09-02 02:07:21.682907Z] Finished download-and-extract step (success) 19:07:21 INFO - [mozharness: 2016-09-02 02:07:21.683136Z] Running populate-webroot step. 19:07:21 INFO - Running pre-action listener: _resource_record_pre_action 19:07:21 INFO - Running main action method: populate_webroot 19:07:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:07:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:07:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:07:21 INFO - '--tppagecycles', 19:07:21 INFO - '7'], 19:07:21 INFO - 'tests': ['tsvgm']}}, 19:07:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:07:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:07:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:07:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:07:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['damp', 'tps']}, 19:07:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'tests': ['damp', 'tps']}, 19:07:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['dromaeo_dom']}, 19:07:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:07:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['basic_compositor_video']}, 19:07:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:07:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['a11yr', 19:07:21 INFO - 'ts_paint', 19:07:21 INFO - 'tpaint', 19:07:21 INFO - 'sessionrestore', 19:07:21 INFO - 'sessionrestore_no_auto_restore', 19:07:21 INFO - 'tabpaint']}, 19:07:21 INFO - 'other-e10s': {'tests': ['a11yr', 19:07:21 INFO - 'ts_paint', 19:07:21 INFO - 'tpaint', 19:07:21 INFO - 'sessionrestore', 19:07:21 INFO - 'sessionrestore_no_auto_restore', 19:07:21 INFO - 'tabpaint']}, 19:07:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:07:21 INFO - 'ts_paint', 19:07:21 INFO - 'tpaint', 19:07:21 INFO - 'sessionrestore', 19:07:21 INFO - 'sessionrestore_no_auto_restore', 19:07:21 INFO - 'tabpaint']}, 19:07:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:07:21 INFO - 'ts_paint', 19:07:21 INFO - 'tpaint', 19:07:21 INFO - 'sessionrestore', 19:07:21 INFO - 'sessionrestore_no_auto_restore', 19:07:21 INFO - 'tabpaint']}, 19:07:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['a11yr', 19:07:21 INFO - 'ts_paint', 19:07:21 INFO - 'tpaint', 19:07:21 INFO - 'sessionrestore', 19:07:21 INFO - 'sessionrestore_no_auto_restore', 19:07:21 INFO - 'tabpaint']}, 19:07:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['a11yr', 19:07:21 INFO - 'ts_paint', 19:07:21 INFO - 'tpaint', 19:07:21 INFO - 'sessionrestore', 19:07:21 INFO - 'sessionrestore_no_auto_restore', 19:07:21 INFO - 'tabpaint']}, 19:07:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['tsvgx', 19:07:21 INFO - 'tsvgr_opacity', 19:07:21 INFO - 'tart', 19:07:21 INFO - 'tscrollx', 19:07:21 INFO - 'cart']}, 19:07:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:07:21 INFO - 'tsvgr_opacity', 19:07:21 INFO - 'tart', 19:07:21 INFO - 'tscrollx', 19:07:21 INFO - 'cart']}, 19:07:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'talos_options': ['--disable-e10s'], 19:07:21 INFO - 'tests': ['tp5o']}, 19:07:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'tests': ['tp5o']}, 19:07:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'talos_options': ['--disable-e10s', 19:07:21 INFO - '--xperf_path', 19:07:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:21 INFO - 'tests': ['tp5n']}, 19:07:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:21 INFO - 'talos_options': ['--xperf_path', 19:07:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:21 INFO - 'tests': ['tp5n']}}, 19:07:21 INFO - 'talos.zip': {'path': '', 19:07:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:07:21 INFO - Running post-action listener: _resource_record_post_action 19:07:21 INFO - [mozharness: 2016-09-02 02:07:21.702913Z] Finished populate-webroot step (success) 19:07:21 INFO - [mozharness: 2016-09-02 02:07:21.703004Z] Running create-virtualenv step. 19:07:21 INFO - Running pre-action listener: _resource_record_pre_action 19:07:21 INFO - Running main action method: create_virtualenv 19:07:21 INFO - Creating virtualenv /builds/slave/test/build/venv 19:07:21 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 19:07:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:07:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:07:21 INFO - Using real prefix '/usr' 19:07:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:07:22 INFO - Installing distribute.............................................................................................................................................................................................done. 19:07:24 INFO - Installing pip.................done. 19:07:24 INFO - Return code: 0 19:07:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:07:24 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')]} 19:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:24 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')]} 19:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:24 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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:24 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 19:07:24 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 19:07:24 INFO - Using env: {'DISPLAY': ':0', 19:07:24 INFO - 'HOME': '/home/cltbld', 19:07:24 INFO - 'LANG': 'en_US.UTF-8', 19:07:24 INFO - 'LANGUAGE': 'en_US:en', 19:07:24 INFO - 'LOGNAME': 'cltbld', 19:07:24 INFO - 'MAIL': '/var/mail/cltbld', 19:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:24 INFO - 'MOZ_NO_REMOTE': '1', 19:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:24 INFO - 'NO_EM_RESTART': '1', 19:07:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:24 INFO - 'PWD': '/builds/slave/test', 19:07:24 INFO - 'SHELL': '/bin/bash', 19:07:24 INFO - 'SHLVL': '1', 19:07:24 INFO - 'TERM': 'linux', 19:07:24 INFO - 'TMOUT': '86400', 19:07:24 INFO - 'USER': 'cltbld', 19:07:24 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:24 INFO - '_': '/tools/buildbot/bin/python'} 19:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:24 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 19:07:24 INFO - Cleaning up... 19:07:24 INFO - Return code: 0 19:07:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:07:24 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')]} 19:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:24 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')]} 19:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:24 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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:24 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 19:07:24 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 19:07:24 INFO - Using env: {'DISPLAY': ':0', 19:07:24 INFO - 'HOME': '/home/cltbld', 19:07:24 INFO - 'LANG': 'en_US.UTF-8', 19:07:24 INFO - 'LANGUAGE': 'en_US:en', 19:07:24 INFO - 'LOGNAME': 'cltbld', 19:07:24 INFO - 'MAIL': '/var/mail/cltbld', 19:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:24 INFO - 'MOZ_NO_REMOTE': '1', 19:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:24 INFO - 'NO_EM_RESTART': '1', 19:07:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:24 INFO - 'PWD': '/builds/slave/test', 19:07:24 INFO - 'SHELL': '/bin/bash', 19:07:24 INFO - 'SHLVL': '1', 19:07:24 INFO - 'TERM': 'linux', 19:07:24 INFO - 'TMOUT': '86400', 19:07:24 INFO - 'USER': 'cltbld', 19:07:24 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:24 INFO - '_': '/tools/buildbot/bin/python'} 19:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:24 INFO - Downloading/unpacking psutil>=3.1.1 19:07:24 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 19:07:24 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 19:07:24 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 19:07:24 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 19:07:24 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 19:07:24 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 19:07:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:07:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:26 INFO - Installing collected packages: psutil 19:07:26 INFO - Running setup.py install for psutil 19:07:26 INFO - building 'psutil._psutil_linux' extension 19:07:26 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 19:07:26 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 19:07:27 INFO - building 'psutil._psutil_posix' extension 19:07:27 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 19:07:27 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 19:07:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:27 INFO - Successfully installed psutil 19:07:27 INFO - Cleaning up... 19:07:27 INFO - Return code: 0 19:07:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:07:27 INFO - Using env: {'DISPLAY': ':0', 19:07:27 INFO - 'HOME': '/home/cltbld', 19:07:27 INFO - 'LANG': 'en_US.UTF-8', 19:07:27 INFO - 'LANGUAGE': 'en_US:en', 19:07:27 INFO - 'LOGNAME': 'cltbld', 19:07:27 INFO - 'MAIL': '/var/mail/cltbld', 19:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:27 INFO - 'MOZ_NO_REMOTE': '1', 19:07:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:27 INFO - 'NO_EM_RESTART': '1', 19:07:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:27 INFO - 'PWD': '/builds/slave/test', 19:07:27 INFO - 'SHELL': '/bin/bash', 19:07:27 INFO - 'SHLVL': '1', 19:07:27 INFO - 'TERM': 'linux', 19:07:27 INFO - 'TMOUT': '86400', 19:07:27 INFO - 'USER': 'cltbld', 19:07:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:27 INFO - '_': '/tools/buildbot/bin/python'} 19:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:07:29 INFO - Installing collected packages: mozsystemmonitor 19:07:29 INFO - Running setup.py install for mozsystemmonitor 19:07:29 INFO - Successfully installed mozsystemmonitor 19:07:29 INFO - Cleaning up... 19:07:29 INFO - Return code: 0 19:07:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19: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')]} 19:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19: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==2.5.1'] in /builds/slave/test/build 19: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==2.5.1 19:07:29 INFO - Using env: {'DISPLAY': ':0', 19:07:29 INFO - 'HOME': '/home/cltbld', 19:07:29 INFO - 'LANG': 'en_US.UTF-8', 19:07:29 INFO - 'LANGUAGE': 'en_US:en', 19:07:29 INFO - 'LOGNAME': 'cltbld', 19:07:29 INFO - 'MAIL': '/var/mail/cltbld', 19:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:29 INFO - 'MOZ_NO_REMOTE': '1', 19:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:29 INFO - 'NO_EM_RESTART': '1', 19:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:29 INFO - 'PWD': '/builds/slave/test', 19:07:29 INFO - 'SHELL': '/bin/bash', 19:07:29 INFO - 'SHLVL': '1', 19:07:29 INFO - 'TERM': 'linux', 19:07:29 INFO - 'TMOUT': '86400', 19:07:29 INFO - 'USER': 'cltbld', 19:07:29 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:29 INFO - '_': '/tools/buildbot/bin/python'} 19:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:29 INFO - Downloading/unpacking jsonschema==2.5.1 19:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:07:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:31 INFO - Downloading functools32-3.2.3-2.tar.gz 19:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:07:31 INFO - warning: no files found matching '*.txt' 19:07:31 INFO - no previously-included directories found matching 'build' 19:07:31 INFO - no previously-included directories found matching 'dist' 19:07:31 INFO - no previously-included directories found matching '.git*' 19:07:31 INFO - Installing collected packages: jsonschema, functools32 19:07:32 INFO - Running setup.py install for functools32 19:07:32 INFO - warning: no files found matching '*.txt' 19:07:32 INFO - no previously-included directories found matching 'build' 19:07:32 INFO - no previously-included directories found matching 'dist' 19:07:32 INFO - no previously-included directories found matching '.git*' 19:07:32 INFO - Successfully installed jsonschema functools32 19:07:32 INFO - Cleaning up... 19:07:32 INFO - Return code: 0 19:07:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:07:32 INFO - Using env: {'DISPLAY': ':0', 19:07:32 INFO - 'HOME': '/home/cltbld', 19:07:32 INFO - 'LANG': 'en_US.UTF-8', 19:07:32 INFO - 'LANGUAGE': 'en_US:en', 19:07:32 INFO - 'LOGNAME': 'cltbld', 19:07:32 INFO - 'MAIL': '/var/mail/cltbld', 19:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:32 INFO - 'MOZ_NO_REMOTE': '1', 19:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:32 INFO - 'NO_EM_RESTART': '1', 19:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:32 INFO - 'PWD': '/builds/slave/test', 19:07:32 INFO - 'SHELL': '/bin/bash', 19:07:32 INFO - 'SHLVL': '1', 19:07:32 INFO - 'TERM': 'linux', 19:07:32 INFO - 'TMOUT': '86400', 19:07:32 INFO - 'USER': 'cltbld', 19:07:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:32 INFO - '_': '/tools/buildbot/bin/python'} 19:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:07:32 INFO - Cleaning up... 19:07:32 INFO - Return code: 0 19:07:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:07:32 INFO - Using env: {'DISPLAY': ':0', 19:07:32 INFO - 'HOME': '/home/cltbld', 19:07:32 INFO - 'LANG': 'en_US.UTF-8', 19:07:32 INFO - 'LANGUAGE': 'en_US:en', 19:07:32 INFO - 'LOGNAME': 'cltbld', 19:07:32 INFO - 'MAIL': '/var/mail/cltbld', 19:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:32 INFO - 'MOZ_NO_REMOTE': '1', 19:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:32 INFO - 'NO_EM_RESTART': '1', 19:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:32 INFO - 'PWD': '/builds/slave/test', 19:07:32 INFO - 'SHELL': '/bin/bash', 19:07:32 INFO - 'SHLVL': '1', 19:07:32 INFO - 'TERM': 'linux', 19:07:32 INFO - 'TMOUT': '86400', 19:07:32 INFO - 'USER': 'cltbld', 19:07:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:32 INFO - '_': '/tools/buildbot/bin/python'} 19:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:32 INFO - Downloading/unpacking blobuploader==1.2.4 19:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:34 INFO - Downloading blobuploader-1.2.4.tar.gz 19:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:07:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:07:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:35 INFO - Downloading docopt-0.6.1.tar.gz 19:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:07:35 INFO - Installing collected packages: blobuploader, requests, docopt 19:07:35 INFO - Running setup.py install for blobuploader 19:07:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:07:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:07:35 INFO - Running setup.py install for requests 19:07:35 INFO - Running setup.py install for docopt 19:07:35 INFO - Successfully installed blobuploader requests docopt 19:07:35 INFO - Cleaning up... 19:07:35 INFO - Return code: 0 19:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:35 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 19:07:35 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 19:07:35 INFO - Using env: {'DISPLAY': ':0', 19:07:35 INFO - 'HOME': '/home/cltbld', 19:07:35 INFO - 'LANG': 'en_US.UTF-8', 19:07:35 INFO - 'LANGUAGE': 'en_US:en', 19:07:35 INFO - 'LOGNAME': 'cltbld', 19:07:35 INFO - 'MAIL': '/var/mail/cltbld', 19:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:35 INFO - 'MOZ_NO_REMOTE': '1', 19:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:35 INFO - 'NO_EM_RESTART': '1', 19:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:35 INFO - 'PWD': '/builds/slave/test', 19:07:35 INFO - 'SHELL': '/bin/bash', 19:07:35 INFO - 'SHLVL': '1', 19:07:35 INFO - 'TERM': 'linux', 19:07:35 INFO - 'TMOUT': '86400', 19:07:35 INFO - 'USER': 'cltbld', 19:07:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:35 INFO - '_': '/tools/buildbot/bin/python'} 19:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:36 INFO - Running setup.py (path:/tmp/pip-JfxGCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:36 INFO - Running setup.py (path:/tmp/pip-6iTbR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:36 INFO - Running setup.py (path:/tmp/pip-nSm61g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:36 INFO - Running setup.py (path:/tmp/pip-iM0HJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:36 INFO - Running setup.py (path:/tmp/pip-iCn10E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:36 INFO - Running setup.py (path:/tmp/pip-tKfczR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:36 INFO - Running setup.py (path:/tmp/pip-FILD78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:36 INFO - Running setup.py (path:/tmp/pip-KGr0Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:36 INFO - Running setup.py (path:/tmp/pip-a331HQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:36 INFO - Running setup.py (path:/tmp/pip-x3WEvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:36 INFO - Running setup.py (path:/tmp/pip-opzsgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:36 INFO - Running setup.py (path:/tmp/pip-g5kbVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:36 INFO - Running setup.py (path:/tmp/pip-9ougUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:37 INFO - Running setup.py (path:/tmp/pip-egF5Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:37 INFO - Running setup.py (path:/tmp/pip-v148Fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:37 INFO - Running setup.py (path:/tmp/pip-TLvgvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:37 INFO - Running setup.py (path:/tmp/pip-e3X_NJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:07:37 INFO - Running setup.py install for manifestparser 19:07:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:07:37 INFO - Running setup.py install for mozcrash 19:07:37 INFO - Running setup.py install for mozdebug 19:07:37 INFO - Running setup.py install for mozdevice 19:07:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:07:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:07:37 INFO - Running setup.py install for mozfile 19:07:37 INFO - Running setup.py install for mozhttpd 19:07:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:07:37 INFO - Running setup.py install for mozinfo 19:07:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Running setup.py install for mozInstall 19:07:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Running setup.py install for mozleak 19:07:38 INFO - Running setup.py install for mozlog 19:07:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Running setup.py install for moznetwork 19:07:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Running setup.py install for mozprocess 19:07:38 INFO - Running setup.py install for mozprofile 19:07:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Running setup.py install for mozrunner 19:07:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:07:38 INFO - Running setup.py install for mozscreenshot 19:07:38 INFO - Running setup.py install for moztest 19:07:39 INFO - Running setup.py install for mozversion 19:07:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:07:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:07:39 INFO - Cleaning up... 19:07:39 INFO - Return code: 0 19:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:39 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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:39 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 19:07:39 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 19:07:39 INFO - Using env: {'DISPLAY': ':0', 19:07:39 INFO - 'HOME': '/home/cltbld', 19:07:39 INFO - 'LANG': 'en_US.UTF-8', 19:07:39 INFO - 'LANGUAGE': 'en_US:en', 19:07:39 INFO - 'LOGNAME': 'cltbld', 19:07:39 INFO - 'MAIL': '/var/mail/cltbld', 19:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:39 INFO - 'MOZ_NO_REMOTE': '1', 19:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:39 INFO - 'NO_EM_RESTART': '1', 19:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:39 INFO - 'PWD': '/builds/slave/test', 19:07:39 INFO - 'SHELL': '/bin/bash', 19:07:39 INFO - 'SHLVL': '1', 19:07:39 INFO - 'TERM': 'linux', 19:07:39 INFO - 'TMOUT': '86400', 19:07:39 INFO - 'USER': 'cltbld', 19:07:39 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:39 INFO - '_': '/tools/buildbot/bin/python'} 19:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:39 INFO - Running setup.py (path:/tmp/pip-vcZsen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:39 INFO - Running setup.py (path:/tmp/pip-eNgK4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:39 INFO - Running setup.py (path:/tmp/pip-QD0UKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:39 INFO - Running setup.py (path:/tmp/pip-rPIAEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:39 INFO - Running setup.py (path:/tmp/pip-FgP_BR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:39 INFO - Running setup.py (path:/tmp/pip-i2DKz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:39 INFO - Running setup.py (path:/tmp/pip-vsnn_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:39 INFO - Running setup.py (path:/tmp/pip-vlzaDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:39 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)) 19:07:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:39 INFO - Running setup.py (path:/tmp/pip-qLXlME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:40 INFO - Running setup.py (path:/tmp/pip-GiId34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:40 INFO - Running setup.py (path:/tmp/pip-JyWpII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:40 INFO - Running setup.py (path:/tmp/pip-c6MSz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:40 INFO - Running setup.py (path:/tmp/pip-8kUx9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:40 INFO - Running setup.py (path:/tmp/pip-O5HYGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:40 INFO - Running setup.py (path:/tmp/pip-LoY_RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:40 INFO - Running setup.py (path:/tmp/pip-wzfQWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:40 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)) 19:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:40 INFO - Running setup.py (path:/tmp/pip-g4Ad8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:42 INFO - Downloading blessings-1.6.tar.gz 19:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:07:42 INFO - Installing collected packages: blessings 19:07:42 INFO - Running setup.py install for blessings 19:07:42 INFO - Successfully installed blessings 19:07:42 INFO - Cleaning up... 19:07:42 INFO - Return code: 0 19:07:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:07:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:42 INFO - Reading from file tmpfile_stdout 19:07:42 INFO - Current package versions: 19:07:42 INFO - argparse == 1.2.1 19:07:42 INFO - blessings == 1.6 19:07:42 INFO - blobuploader == 1.2.4 19:07:42 INFO - docopt == 0.6.1 19:07:42 INFO - functools32 == 3.2.3-2 19:07:42 INFO - jsonschema == 2.5.1 19:07:42 INFO - manifestparser == 1.1 19:07:42 INFO - mozInstall == 1.12 19:07:42 INFO - mozcrash == 0.17 19:07:42 INFO - mozdebug == 0.1 19:07:42 INFO - mozdevice == 0.48 19:07:42 INFO - mozfile == 1.2 19:07:42 INFO - mozhttpd == 0.7 19:07:42 INFO - mozinfo == 0.9 19:07:42 INFO - mozleak == 0.1 19:07:42 INFO - mozlog == 3.3 19:07:42 INFO - moznetwork == 0.27 19:07:42 INFO - mozprocess == 0.23 19:07:42 INFO - mozprofile == 0.28 19:07:42 INFO - mozrunner == 6.12 19:07:42 INFO - mozscreenshot == 0.1 19:07:42 INFO - mozsystemmonitor == 0.3 19:07:42 INFO - moztest == 0.7 19:07:42 INFO - mozversion == 1.4 19:07:42 INFO - psutil == 3.1.1 19:07:42 INFO - requests == 1.2.3 19:07:42 INFO - wsgiref == 0.1.2 19:07:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:42 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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:42 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 19:07:42 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 19:07:42 INFO - Using env: {'DISPLAY': ':0', 19:07:42 INFO - 'HOME': '/home/cltbld', 19:07:42 INFO - 'LANG': 'en_US.UTF-8', 19:07:42 INFO - 'LANGUAGE': 'en_US:en', 19:07:42 INFO - 'LOGNAME': 'cltbld', 19:07:42 INFO - 'MAIL': '/var/mail/cltbld', 19:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:42 INFO - 'MOZ_NO_REMOTE': '1', 19:07:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:42 INFO - 'NO_EM_RESTART': '1', 19:07:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:42 INFO - 'PWD': '/builds/slave/test', 19:07:42 INFO - 'SHELL': '/bin/bash', 19:07:42 INFO - 'SHLVL': '1', 19:07:42 INFO - 'TERM': 'linux', 19:07:42 INFO - 'TMOUT': '86400', 19:07:42 INFO - 'USER': 'cltbld', 19:07:42 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:42 INFO - '_': '/tools/buildbot/bin/python'} 19:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 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)) 19:07:43 INFO - Cleaning up... 19:07:43 INFO - Return code: 0 19:07:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:43 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 0x7f987cda5f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, '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': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', '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 19:07:43 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 19:07:43 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 19:07:43 INFO - Using env: {'DISPLAY': ':0', 19:07:43 INFO - 'HOME': '/home/cltbld', 19:07:43 INFO - 'LANG': 'en_US.UTF-8', 19:07:43 INFO - 'LANGUAGE': 'en_US:en', 19:07:43 INFO - 'LOGNAME': 'cltbld', 19:07:43 INFO - 'MAIL': '/var/mail/cltbld', 19:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:43 INFO - 'MOZ_NO_REMOTE': '1', 19:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:43 INFO - 'NO_EM_RESTART': '1', 19:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:43 INFO - 'PWD': '/builds/slave/test', 19:07:43 INFO - 'SHELL': '/bin/bash', 19:07:43 INFO - 'SHLVL': '1', 19:07:43 INFO - 'TERM': 'linux', 19:07:43 INFO - 'TMOUT': '86400', 19:07:43 INFO - 'USER': 'cltbld', 19:07:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:43 INFO - '_': '/tools/buildbot/bin/python'} 19:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:07:43 INFO - Cleaning up... 19:07:43 INFO - Return code: 0 19:07:43 INFO - Running post-action listener: _resource_record_post_action 19:07:43 INFO - Running post-action listener: _start_resource_monitoring 19:07:43 INFO - Starting resource monitoring. 19:07:43 INFO - [mozharness: 2016-09-02 02:07:43.430881Z] Finished create-virtualenv step (success) 19:07:43 INFO - [mozharness: 2016-09-02 02:07:43.431355Z] Running install step. 19:07:43 INFO - Running pre-action listener: _resource_record_pre_action 19:07:43 INFO - Running main action method: install 19:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:43 INFO - Reading from file tmpfile_stdout 19:07:43 INFO - Detecting whether we're running mozinstall >=1.0... 19:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:07:43 INFO - Reading from file tmpfile_stdout 19:07:43 INFO - Output received: 19:07:43 INFO - Usage: mozinstall [options] installer 19:07:43 INFO - Options: 19:07:43 INFO - -h, --help show this help message and exit 19:07:43 INFO - -d DEST, --destination=DEST 19:07:43 INFO - Directory to install application into. [default: 19:07:43 INFO - "/builds/slave/test"] 19:07:43 INFO - --app=APP Application being installed. [default: firefox] 19:07:43 INFO - mkdir: /builds/slave/test/build/application 19:07:43 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'] 19:07:43 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 19:07:59 INFO - Reading from file tmpfile_stdout 19:07:59 INFO - Output received: 19:07:59 INFO - /builds/slave/test/build/application/firefox/firefox 19:07:59 INFO - Running post-action listener: _resource_record_post_action 19:07:59 INFO - [mozharness: 2016-09-02 02:07:59.904709Z] Finished install step (success) 19:07:59 INFO - [mozharness: 2016-09-02 02:07:59.904992Z] Running run-tests step. 19:07:59 INFO - Running pre-action listener: _resource_record_pre_action 19:07:59 INFO - Running main action method: run_tests 19:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:07:59 INFO - Python 2.7.3 19:07:59 INFO - Return code: 0 19:07:59 INFO - grabbing minidump binary from tooltool 19:07: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')]} 19:07:59 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 0x16c1090>, '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 0x16c49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c4e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:07:59 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 19:07:59 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 19:07:59 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 19:07:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:07:59 INFO - Return code: 0 19:07:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:07:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:07:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:07:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:07:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 19:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 19:07:59 INFO - Using env: {'DISPLAY': ':0', 19:07:59 INFO - 'HOME': '/home/cltbld', 19:07:59 INFO - 'LANG': 'en_US.UTF-8', 19:07:59 INFO - 'LANGUAGE': 'en_US:en', 19:07:59 INFO - 'LOGNAME': 'cltbld', 19:07:59 INFO - 'MAIL': '/var/mail/cltbld', 19:07:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:07:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:59 INFO - 'MOZ_NO_REMOTE': '1', 19:07:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:59 INFO - 'NO_EM_RESTART': '1', 19:07:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:59 INFO - 'PWD': '/builds/slave/test', 19:07:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:07:59 INFO - 'SHELL': '/bin/bash', 19:07:59 INFO - 'SHLVL': '1', 19:07:59 INFO - 'TERM': 'linux', 19:07:59 INFO - 'TMOUT': '86400', 19:07:59 INFO - 'USER': 'cltbld', 19:07:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1472780962.49334-283195181', 19:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:59 INFO - '_': '/tools/buildbot/bin/python'} 19:07:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 19:08:00 INFO - mozversion application_buildid: 20160901174542 19:08:00 INFO - mozversion application_changeset: 2d457db23476fecab9e6e66a737c6723d0d2767c 19:08:00 INFO - mozversion application_display_name: Nightly 19:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:00 INFO - mozversion application_name: Firefox 19:08:00 INFO - mozversion application_remotingname: firefox 19:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:08:00 INFO - mozversion application_vendor: Mozilla 19:08:00 INFO - mozversion application_version: 51.0a1 19:08:00 INFO - mozversion platform_buildid: 20160901174542 19:08:00 INFO - mozversion platform_changeset: 2d457db23476fecab9e6e66a737c6723d0d2767c 19:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:08:00 INFO - mozversion platform_version: 51.0a1 19:08:00 INFO - using testdate: 1472782080 19:08:00 INFO - actual date: 1472782080 19:08:00 INFO - starting webserver on 'localhost:38212' 19:08:00 INFO - SUITE-START | Running 2 tests 19:08:00 INFO - TEST-START | dromaeo_css 19:08:00 INFO - operating with platform_type : linux_ 19:08:00 INFO - Initialising browser for dromaeo_css test... 19:08:00 INFO - TEST-INFO | started process 4181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0sL0jV/profile http://localhost:38212/getInfo.html) 19:08:05 INFO - PROCESS | 4181 | __metrics Screen width/height:1600/1200 19:08:05 INFO - PROCESS | 4181 | colorDepth:24 19:08:05 INFO - PROCESS | 4181 | Browser inner width/height: 1024/697 19:08:05 INFO - PROCESS | 4181 | __metrics 19:08:06 INFO - PROCESS | 4181 | 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" 19:08:06 INFO - PROCESS | 4181 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:08:06 INFO - PROCESS | 4181 | console.error: PushService: 19:08:06 INFO - PROCESS | 4181 | stateChangeProcessEnqueue: Error transitioning state 19:08:06 INFO - PROCESS | 4181 | UnknownError 19:08:07 INFO - TEST-INFO | 4181: exit 0 19:08:07 INFO - Browser initialized. 19:08:07 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:07 INFO - TEST-INFO | started process 4265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0sL0jV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:19 INFO - PROCESS | 4265 | RSS: Main: 181780480 19:08:19 INFO - PROCESS | 4265 | 19:09:10 INFO - PROCESS | 4265 | 1472782150526 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] 19:11:23 INFO - PROCESS | 4265 | Cycle 1(1): loaded http://localhost:38212/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38212/tests/dromaeo/cssquery-ext.html) 19:11:25 INFO - PROCESS | 4265 | RSS: Main: 269094912 19:11:25 INFO - PROCESS | 4265 | 19:14:27 INFO - PROCESS | 4265 | Cycle 1(1): loaded http://localhost:38212/tests/dromaeo/cssquery-ext.html (next: http://localhost:38212/tests/dromaeo/cssquery-jquery.html) 19:14:28 INFO - PROCESS | 4265 | RSS: Main: 277037056 19:14:28 INFO - PROCESS | 4265 | 19:17:30 INFO - PROCESS | 4265 | Cycle 1(1): loaded http://localhost:38212/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38212/tests/dromaeo/cssquery-mootools.html) 19:17:31 INFO - PROCESS | 4265 | RSS: Main: 218112000 19:17:31 INFO - PROCESS | 4265 | 19:20:33 INFO - PROCESS | 4265 | Cycle 1(1): loaded http://localhost:38212/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38212/tests/dromaeo/cssquery-prototype.html) 19:20:34 INFO - PROCESS | 4265 | RSS: Main: 239906816 19:20:34 INFO - PROCESS | 4265 | 19:23:37 INFO - PROCESS | 4265 | Cycle 1(1): loaded http://localhost:38212/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38212/tests/dromaeo/cssquery-yui.html) 19:23:37 INFO - PROCESS | 4265 | RSS: Main: 218460160 19:23:37 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | Cycle 1(1): loaded http://localhost:38212/tests/dromaeo/cssquery-yui.html (next: http://localhost:38212/tests/dromaeo/cssquery-dojo.html) 19:26:41 INFO - PROCESS | 4265 | RSS: Main: 307261440 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | __start_tp_report 19:26:41 INFO - PROCESS | 4265 | _x_x_mozilla_page_load 19:26:41 INFO - PROCESS | 4265 | _x_x_mozilla_page_load_details 19:26:41 INFO - PROCESS | 4265 | |i|pagename|runs| 19:26:41 INFO - PROCESS | 4265 | |0;dojo.html;2592.027989503936;2616.9869150654245;2617.0447786557907;2661.840289582624;2665.426933209361;4607.354970304152;4643.628509719223;4665.230237010897;4701.50634183411;4785.928211076836;4757.785899634516;4766.928496072553;4792.185328494155;4805.807767689288;4852.636052296083;4313.374560688699;4348.934765978513;4408.537103604123;4474.6196573291145;4498.640108791289;4415.712978656377;4454.599086082252;4474.328850672393;4493.123840851035;4505.662075344346;4273.594008569181;4305.22505948929;4348.391225228471;4370.868873933787;4418.80115394808;4305.827766889321;4334.393184954109;4471.351654010183;4474.753888536129;4493.236149854532;4331.112122014979;4338.219120558329;4348.260795664744;4385.122975404932;4391.165678521071;4543.954560454419;4741.2651039089;4742.525747425264;4743.739094349809;4745.881352966169;4923.113839508887;4969.652124351297;4970.403551573798;4972.676776009548;4978.278149668314;4985.775640096168;5004.649674522785;5021.874453138665;5095.694258344512;5108.897822043576;4582.198115329804;4687.132880417065;4688.062387522475;4694.647901407408;4717.056588682277;5211.088059589557;5279.155335146358;5302.761375738101;5303.28405665238;5306.389765177054;5003.5997120230295;5008.073506401327;5011.674241174385;5014.749262536931;5060.797568097235;5104.693718376835;5104.979004199175;5112.667676601009;5158.277841102249;5177.611679124081;4923.655344125877;5008.098542262355;5034.496550344937;5077.669951453144;5079.060373830853;5076.416212135652;5080.415752188472;5084.898302034013;5085.923711144335;5123.001014802152;4316.482022157361;4317.438732964694;4319.892002699958;4359.956400436018;4363.541828108003;3719.683826874692;3727.440883867387;3750.156214851691;3751.512303400595;3779.7543159694533;4396.450443694538;4418.116376724668;4454.621357184611;4470.508244093147;4472.105578884236;5079.74601269935;5120.462351453044;5126.512981266774;5130.1535246684625;5135.6918584885025;4321;4376.846810361686;4440.666949978765;4445.110977804452;4490.281554951257;4575.405197324393;4594.989102397467;4596.080783843245;4649.116667833168;4669.066186762661;4766.856994290177;4801.01579176268;4803.5916947059895;4848.369711937426;4853.538913803183;4979.0788704089855;5021.271915572149;5054.21659642756;5131.769070391767;5161.690298581947;4728.456227533878;4738.952610473852;4772.880677983078;4803.903921921472;4819.662623616313;4994.450610432777;4998.125328067501;5000.024995125916;5000.474950130184;5065.848024559269;4897.559219670246;5065.4174769900455;5075.492450754895;5079.263506791568;5143.92284115746;4929.507049295042;4962.776675049559;4979.352684151182;4999.575036121971;5052;4970.801167953241;4982.003599280086;4985.925211121764;5000.324956130876;5041.369828771403;4746.905061898812;4907.730074845917;4922.237053256751;4928.186849169847;4989.027139707722;4932.45742968266;4954.925676114789;4962.900741985212;4983.925241121314;5019.472955339783;4875.634327425528;4878;4878.902421951612;4882.414110306786;4931.48219436954 19:26:41 INFO - PROCESS | 4265 | |1;ext.html;546.3089192171902;559.56074481532;562.3701454371105;563.2733773432273;567.1322875999721;27697.16908492746;36919.446208306894;38373.42439863397;38486.80756596217;42236.78881605595;38715.61284387155;39061.804690976576;39121.804390978;39151.60848391515;39155;7632.198619144993;7780.377569794431;7780.80547986302;7786.377089832799;7788.571628560427;7245.239249878739;9704.805903881917;9978.550965206547;9978.60085596579;10321.793564128713;9975.600975960953;10051.798964020716;10051.849222261671;10064.094231519162;10123.949380253089;9871.40771553709;9922.851157232648;9936.205103591696;9945.850812237823;9948.651797187136;9792.951035244778;9882.851757223647;9890.802183956352;9900.90099099007;9901.108900198944;36652.083697907765;36817.63182368169;36935.63064369349;36974.445383319005;36981.260374792626;2897.3046468847324;3002.774791890617;3103.131123285461;3173.926407741965;3274.4269752793166;28021.299467513272;28341.433171336663;28484.145475635763;28888;28960;3506.8596314775054;3519.1378112362636;3551.183227857607;3594.658507441789;3614.204874927512;252225;252560;252882;252915;253130;30104.849475752482;31027.844860775553;49121;49470.752646236535;49655;32900.67099328976;32943.176420589196;33041;33159;33190.66809331924;12361.258324500379;12376.690582735504;12386.071044671686;12392.070594705434;12397.876021239852;9362.438253704799;9570.61717531305;11173.553057877756;11298.378589177673;11298.943505282421;7289.635518224173;7350.595717235603;7470.244492544248;7740.109887362913;7778.494397864234;2980.8956686516303;3031.363413683107;3050.8779648814243;3072.2165847708693;3076.0771768469817;861.724248240557;871.005215156079;872.4024043530202;886.7650072730731;921.9354645174908;26702.866485667837;27203.86398067997;27337.589936150973;27607.30981725473;28105.71894281072;897.7261935109724;899.4153800030033;908.7728067983004;941.8932754014006;964.2141654551658;13869.787710390541;14537.41850325975;14829.62925926839;14851.925740371229;14858.108513489226;389072;393961;394166;394234;394561;47438.52561474341;47869.5213047872;47954.520454795704;47963.280550791766;47967.760161198974;1101.7465982823996;1127.4700890581414;1146.2606618730872;1149.3333866357493;1177.7879981603223;25268.241952741446;26402.73597264003;26734.86632566825;27174.592381114693;27214.319642009108;41287;41554;41596.1680766389;41612.3758143622;41667;318048;318739;320417;320637;321444;21755;23860.88069559641;24325.513489729754;24983.125590604242;25394.365140870887;40361.5783053006;40607.59392406156;40702.79648601738;40774.59225407708;41030.58969410268;34689.14351990673;35188;35407;35715.82142089377;36045.45931810972;33608.66391336056;33958.66041339555;34219.4867076999;34289.314213715086;34583.13542161466;34655.65344346524;34656;34931;34941.650583493836;36254.09364765902;30338.393232134793;32748.836255819522;32916.341673166884;32975;32975.8351208252;24684.876575617724;25198.62202067007;25381;25780.613290800276;25800.225993220232 19:26:41 INFO - PROCESS | 4265 | |2;jquery.html;364.92336609312025;374.6665467733717;376.54814222932487;381.7766606535177;387.9398693202553;15759.133247671374;16590.170491475423;16650.16749162543;16743.162841857888;16757.664846703057;15657;16047.598810029755;16383.09892955887;16470;16510.66978660426;5001.474845141262;5229.869253268661;5234.057869583474;5235.110031294689;5242.423333433319;3678.7057035437233;3871.7870517121546;3872.380419132939;3929.0373858404732;5132.717700526469;6209.316975132731;6210.161628180205;6214.78811631732;6216.751329946796;6218.378162183768;5996.0706090555595;6049.516038716914;6137.539684523678;6192.442680158785;6200;5380.973095134538;5385.919211211835;5391.353037635509;5391.56867450603;5403.297571315743;19872;20218.797812021985;20342.08460619276;20563;20655;1889.1121173048782;1973.2304401283511;2073.5956488484753;2153.881536515491;2346.77705617966;12568.371581420985;13312.134711243834;13583.592492225249;13616.046876718634;13779.517716879862;1708.1544635405553;1767.6111255523765;1768.3722278591044;1773.15775006872;1827.3147569661378;68559;68817.31182688159;69068.6546567264;70115.29884701139;71430.64284678543;9463.823320636155;9949.651762188289;9950.35322704022;10096.242781791396;10274.640387586398;13439.596812095653;13452.798208026887;13460.798088028687;13479.595612131652;13502.527411540548;7388.963055184689;7480.9625951870985;7625.504342217738;7661.731839385595;7681.193474685189;7059.470539709439;7113.57318560888;7150.070490836262;7295.6352182390665;7319.597422141834;6127.295361033449;6136.570440069241;6174.746217630533;6202.751889924442;6227.1282020517165;1704.9062301573533;1744.3807448355906;1751.1156865782953;1764.6558921010537;1777.5378401615674;825.5005721538503;873.0178549132227;882.1751662195867;886.4814083761072;887.3744010472731;17228.397006104977;17241.56896077608;17738.733918991227;17969.640607187783;18279.451616451526;816.4570560577268;844.5718559142465;864.9754460184843;868.1925808997693;920.1396694091046;9786;9972.551235194434;10002.149817265614;10179.338343007681;10218.591256349662;396799;398546;398632;404636;412887;85135;86351.1364886343;89466;90316.5484172575;91630;874.4709450782312;911.6900253913701;945.5083356654501;949.2738055387634;952.8475443928938;14591.92704036473;15545.615832479481;16178.191090445196;16246.593835154215;16512;38873.80563097166;40094;40345.79827100963;40523;41045.58954410418;50922.74538627283;52698.20952685789;55978;56607.71696141493;57666.42333576778;7636.274553917369;7707.88438173439;7764.0683118023935;7847.058352997677;8051.4430985492845;9232.353735238468;11497.482613282255;11589.6523104307;11784.882151178379;11956.820647690465;9486.620535178516;9740.512974351395;10113.64602238918;10218.182545396201;10330.535125919201;9738.172255358373;9999.900001000187;10079.193664506969;10257.692269231935;10481.737956551147;10422.635207767995;10587.841182382117;10685.145188384755;10902.78194436091;11029.779404411707;6653.567518111307;6912.723491060301;6932.61870597122;7419.703211871465;7740.612969351622;8743.431676940978;10179.440130792878;10274.743131421774;10298.742531436474;10759.300645458334 19:26:41 INFO - PROCESS | 4265 | |3;mootools.html;1212.5331747277298;1217.9878201217984;1255.378587599138;1300.6618279247396;1682.5793551612096;7482.102147742265;7872;7909.288164065233;8156.551389673566;8186.97662792151;7980.321672657831;8089.110197878229;8107.959460202691;8111.391645626572;8120.675172993073;2159.406163305088;2179.0847843905517;2181.6727490876337;2182.388931099295;2183.235867446389;2103.11669098978;2171.945701357463;2174.913003479859;2175.8476906616543;2180.9345719628436;2148.2373757316163;2155.644318687415;2158;2159.255057005331;2194.221051526709;2109.968350474744;2111.778263282364;2112.6725357569526;2112.93661190163;2114.4925217947657;2103.9474013149643;2152.3435352217566;2152.4834039830484;2153.1387445022115;2159.8704077755383;7744.845103097907;8030.317423019051;8059.758207253792;8134.796630084295;8137.715179968673;4396.604305612478;4616.330632058366;4617.053504031665;4627.976860115711;4631.235846085426;2718.592211168341;2856.5429531275104;2894.1751600793673;2912.825230486156;2921.2842853500815;941.38809773647;943.179433892511;947.8530827721714;958.5925981457852;961.2550273537994;11027.1729620278;11081.058110060654;11097.056750176245;11120.277181983227;11124.054455371303;4023.879283621496;4067.9389809152294;4165.75005499671;4190.580941905785;4240.1307731914885;8651.178138076873;8711.695090671796;8719.564021798884;8721.563921803885;8721.651133954698;4634.72191668501;4641.350210970467;4650.162970665245;4653.046125544255;4659.138059458943;3661.761985470936;4052.5542190359038;4237.724547904377;4281.443412356373;4307.65538756905;3345.397828390913;3371.359441706068;3481.5822101347494;3490.5287786148547;3497.0557949353533;983.2527279267808;999.1557134221571;1009.7778488732467;1018.4856647393019;1026.6509386808373;696.4677018049775;741.9035525381665;784.6351446577255;818.8894499242526;826.5123577089546;5084.771185296597;5231.424543300234;5243.580513558906;5347.064263753828;5363.651362661414;408.57304117197003;410.1080150672276;410.1653135868493;413.6194700875142;417.05328903389136;3451.3960056990427;3537.1334023163977;3540.6990405815277;3575.2670702506443;3659.505966694462;9617.51912404377;10076.949615251877;10091.344062635904;10098.14165795901;10104.646337378157;9652.22782177424;9983.900160998297;10001.14990225839;10002.549885255181;10004.149647279917;7465.962670186723;7506.061742282215;7520.661570229244;7574.053243344582;7576.128745194263;5122;5137.434882162885;5229.581633469389;5293.550048245942;5518.779248830162;9179.862302065341;9233.169014788702;9282.396644218104;9291.67479138227;9358.064193580587;9249.352545322032;9255.25957923378;9538.856917146388;9539;9557.856632150384;5758;6232.532560057924;6382.776602818879;6441.581297215666;6463.192100987377;7520.360769334668;7695.076590809139;7792.37660987131;7793.29860312552;7794.337481313925;6947.826304342229;6990.580565166107;6996.965015175095;7003.509754317149;7240;6783.762568310085;6870.141232345957;6896.034555162381;6907.447404207752;7012.158540975116;6231.158793562811;6280.27776805655;6280.811575652738;6333.746650133943;6337.96831015842;4581.862544123548;4668.626509879134;4703.647226457961;4896.21660534327;4936.925946110883;4610.10103029906;4660.906781864276;4770.9980904010545;4835.685680430761;4870.78081486341 19:26:41 INFO - PROCESS | 4265 | |4;prototype.html;294.90710426215725;297.9925501862454;303.1814101924803;307.5218035954092;311.15676516639905;4572.222722137236;4752.811797050737;4793.424789025313;4795.3766010418685;4839.024000239853;4577.725336479813;4674.228752255874;4674.6260299176065;4676.368690226818;4688.249880019198;3764.6635444417248;3768.5854555998953;3769.6230376962358;3771.641694039062;3773.2642134783728;3587.192881601645;3671.192337685705;3770;3771.830267637949;3776.924461510768;3723.515942927429;3739.6447337502896;3740.5511338639403;3748.5501739791125;3761.9435708464393;3734.9066273343383;3737.8878633640898;3739.2708421857747;3741.6071312512204;3744.906377340561;3327.4028466336295;3330.3015462114254;3333.4833100869405;3336.73306135511;3344.715699165574;4453.198424283628;4583.862484125481;4591.3572099905705;4617.746023968681;4622.95377046232;4253.340732186516;4255.2978758504805;4262.339337402707;4262.595053469915;4263.232618128735;4921.487933514451;4925.507449255082;4925.546963645697;4926.852194434173;4941.925871111936;3309.9834500827583;3503.404421248394;3623.8912832615065;3631.8184090795617;3641.198936234051;5077.857482066543;5127.461616530248;5128.794848206067;5148.639595228336;5152.330197074394;3559.804210768381;3564.946525802115;3567.786952436184;3582.7133829293593;3586.30067136911;3613.223157021253;3631.291898079902;3634.909127271839;3637.1634524059614;3638.2541578976247;3970.3647416413237;4131.628153466202;4134.834606615702;4178.143480586473;4195.8741237762915;4919.557239848359;4924.9507504925205;4925.655204135676;4927.655064145475;4927.852164435073;1188.7563049574817;1233.5065973610629;1253.9874601254053;1254.7553227120625;1269.3843485909326;842.8019415437367;857.5455008845322;870.7504730711323;875.2953872132994;881.5724373678761;4413.426254586947;4413.470383553994;4420.646348292129;4433.977830110829;4445.355423463579;5022.271770593244;5038.077914809475;5046.596272298208;5074.447886479479;5096.949030509648;4408.206522825923;4418.558144185556;4422.159789639958;4433;4443.844465443759;2299.2987138922454;2374.952500949971;2386.9880650596983;2401.9159329423387;2404.2546810488802;4247.299195632748;4261.893452663646;4265.573442655772;4270.338097594878;4275.486941567032;4567.10941366437;4581.679282450196;4589.65577581676;4597.310403439511;4604.88487787808;4036.3945408189006;4227.9365809512265;4240.307404797975;4255.340422234559;4265;4456.68803183774;4474.79863406141;4474.9328760069275;4508.729476231437;4517.221908526299;4408.977955110204;4410.33844923264;4412.669049821212;4414.889627759332;4429.867103986886;4270.676090411982;4277.251480990876;4304.461942257218;4476.2837945928495;4829.44461386945;3557.8932632021083;3568.626078959567;3588.8743893963588;3592.5868525119945;3593.460980852893;5108.208227724708;5119.6416250862085;5123.92314115296;5125.74371281427;5129.846104616868;4751.382320298478;4850.53919877611;4953.851384458472;4958.752062396938;4966.652334336562;4942.456329803646;4942.901141977069;4954.752262386938;4967.875803104951;4974.3584617077;4879.316895634685;4914.606831453547;4933.531314525115;4941.382327209099;4942;4776.665633405628;4998.350214472095;5036.053908463444;5036.269740887477;5043.5712964398435;4885.5358740919555;4928.112939670894;4928.482509336612;4937.851864444073;4941.431735350337 19:26:41 INFO - PROCESS | 4265 | |5;yui.html;622.3185611755127;660.659760223485;681.7273090763691;713.700245896723;716.953398029128;12663.873361266385;13607.65980850478;13690.452381904724;13717.65705857354;14042.50851220205;12096.395180240976;12112.27326360419;12113.032307084777;12119.091068169879;12160.513579456834;584.8830233953227;673.989016475287;713.7252157919213;714.8534550417178;735.4667865797308;729.7737701312584;737.0234439367832;738.4867517075635;740.9481336306461;741.8293792427745;778.1751343575828;780.1223623423648;781.2421950707804;782.0459039971227;782.1083964280724;599.547341756975;740.6074780366414;774.8760198368291;776.7164984780543;776.8174478997431;569.3281927325754;569.7863301262026;569.9544036477114;570.3640440908357;570.5863249144379;12530.310832904186;12546.435410406553;12550.121491495598;12557.309347985856;12560.81158782619;12126.27242365461;12304.200226985216;12337.568185113478;12373.946557804715;12506.49974001039;11464.541418343257;11494.712632184179;11661.941690291578;11790.823137652942;12110.636680899586;3893.1629699614437;3956.6043395660204;3970.563238043813;3972.602739726033;4010.7393019453934;65889.01166482507;67686.66156669185;67817;67956.6602166986;67978;9807.558659860324;10089.747756306004;10272.126869216201;10468.162546996222;10524.842127368096;18592.907035464737;18675.626487470447;18757.155927983273;18812.81187188138;18872.716909246374;6595.835104122435;6624.86750264992;6694.397504224643;6695.4308883745425;6742.258351581322;3623.6919861811516;3630.183208778004;3651.8174091295327;3731.4962480065387;3751.718621103428;2834.8724307406214;2926.678065412803;2930.6483222013494;2931.0620601407345;2932.105707759154;482.2669542295761;484.38241242415495;484.9078675051729;496.08967544555424;503.4889587069128;512.4619149892598;514.5626217714912;521.8538809133448;534.1080395739125;538.2142072574007;7007.789766307019;7258;7261.194216217587;7320.743773967992;7494.70021199146;439.1239477242857;495.56142813609495;521.5123859191668;536.3590509341377;536.9946300537023;3473.3400653875683;3658.7804731716183;3713.962860371362;3725.4970578971493;3763.5671897731563;103892.48053759834;106060.46969765256;106148.46925765477;106558.46720766502;108455;82668.17331826725;84353.57823210844;84818.57590712007;89690.55154724185;89770.55114724515;72844;81168.18831811607;81199;81510;81655.591722041;6689.531732778659;7060.646967651494;7144.4641651877355;7182.676779544829;7271.818204544928;31510.369792604477;31744.206394840312;36247;41188;42978.18100022519;41407.792961035004;52398.47601523936;60059.69970150121;60116.39883601108;61867.381326186165;11047.779044419227;11099.611513596985;11227.101831853595;11465.94267028693;11860.703482413008;11814.637072585689;12207;12364.876351236373;13149.60551183466;13163.276019819003;11623.59317423886;11794.056475482112;12015.75968480643;12160.513579456723;12538.122331436713;10838.729031774268;11228.438578071227;11318.377489238172;11750.412479376168;11891.10816688769;10775.245732798629;11396.94301528487;11399.544018239178;12100.576479823163;12386.132970691964;9789.31474796786;9878.901210988086;10116.145755445714;10171.847422288523;10333.948330258301;8125.878111828446;8417.789555261168;8587.95706021466;8969.461832290084;10053.145482634058 19:26:41 INFO - PROCESS | 4265 | __end_tp_report 19:26:41 INFO - PROCESS | 4265 | __start_cc_report 19:26:41 INFO - PROCESS | 4265 | _x_x_mozilla_cycle_collect,1083 19:26:41 INFO - PROCESS | 4265 | __end_cc_report 19:26:41 INFO - PROCESS | 4265 | __startTimestamp1472783201395__endTimestamp 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | ------- Summary: start ------- 19:26:41 INFO - PROCESS | 4265 | Number of tests: 6 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | [#0] dojo.html Cycles:165 Average:4702.02 Median:4819.66 stddev:492.70 (10.2%) stddev-sans-first:465.58 19:26:41 INFO - PROCESS | 4265 | Values: 2592.0 2617.0 2617.0 2661.8 2665.4 4607.4 4643.6 4665.2 4701.5 4785.9 4757.8 4766.9 4792.2 4805.8 4852.6 4313.4 4348.9 4408.5 4474.6 4498.6 4415.7 4454.6 4474.3 4493.1 4505.7 4273.6 4305.2 4348.4 4370.9 4418.8 4305.8 4334.4 4471.4 4474.8 4493.2 4331.1 4338.2 4348.3 4385.1 4391.2 4544.0 4741.3 4742.5 4743.7 4745.9 4923.1 4969.7 4970.4 4972.7 4978.3 4985.8 5004.6 5021.9 5095.7 5108.9 4582.2 4687.1 4688.1 4694.6 4717.1 5211.1 5279.2 5302.8 5303.3 5306.4 5003.6 5008.1 5011.7 5014.7 5060.8 5104.7 5105.0 5112.7 5158.3 5177.6 4923.7 5008.1 5034.5 5077.7 5079.1 5076.4 5080.4 5084.9 5085.9 5123.0 4316.5 4317.4 4319.9 4360.0 4363.5 3719.7 3727.4 3750.2 3751.5 3779.8 4396.5 4418.1 4454.6 4470.5 4472.1 5079.7 5120.5 5126.5 5130.2 5135.7 4321.0 4376.8 4440.7 4445.1 4490.3 4575.4 4595.0 4596.1 4649.1 4669.1 4766.9 4801.0 4803.6 4848.4 4853.5 4979.1 5021.3 5054.2 5131.8 5161.7 4728.5 4739.0 4772.9 4803.9 4819.7 4994.5 4998.1 5000.0 5000.5 5065.8 4897.6 5065.4 5075.5 5079.3 5143.9 4929.5 4962.8 4979.4 4999.6 5052.0 4970.8 4982.0 4985.9 5000.3 5041.4 4746.9 4907.7 4922.2 4928.2 4989.0 4932.5 4954.9 4962.9 4983.9 5019.5 4875.6 4878.0 4878.9 4882.4 4931.5 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | [#1] ext.html Cycles:180 Average:46046.72 Median:19224.26 stddev:86285.92 (448.8%) stddev-sans-first:86460.35 19:26:41 INFO - PROCESS | 4265 | Values: 546.3 559.6 562.4 563.3 567.1 27697.2 36919.4 38373.4 38486.8 42236.8 38715.6 39061.8 39121.8 39151.6 39155.0 7632.2 7780.4 7780.8 7786.4 7788.6 7245.2 9704.8 9978.6 9978.6 10321.8 9975.6 10051.8 10051.8 10064.1 10123.9 9871.4 9922.9 9936.2 9945.9 9948.7 9793.0 9882.9 9890.8 9900.9 9901.1 36652.1 36817.6 36935.6 36974.4 36981.3 2897.3 3002.8 3103.1 3173.9 3274.4 28021.3 28341.4 28484.1 28888.0 28960.0 3506.9 3519.1 3551.2 3594.7 3614.2 252225.0 252560.0 252882.0 252915.0 253130.0 30104.8 31027.8 49121.0 49470.8 49655.0 32900.7 32943.2 33041.0 33159.0 33190.7 12361.3 12376.7 12386.1 12392.1 12397.9 9362.4 9570.6 11173.6 11298.4 11298.9 7289.6 7350.6 7470.2 7740.1 7778.5 2980.9 3031.4 3050.9 3072.2 3076.1 861.7 871.0 872.4 886.8 921.9 26702.9 27203.9 27337.6 27607.3 28105.7 897.7 899.4 908.8 941.9 964.2 13869.8 14537.4 14829.6 14851.9 14858.1 389072.0 393961.0 394166.0 394234.0 394561.0 47438.5 47869.5 47954.5 47963.3 47967.8 1101.7 1127.5 1146.3 1149.3 1177.8 25268.2 26402.7 26734.9 27174.6 27214.3 41287.0 41554.0 41596.2 41612.4 41667.0 318048.0 318739.0 320417.0 320637.0 321444.0 21755.0 23860.9 24325.5 24983.1 25394.4 40361.6 40607.6 40702.8 40774.6 41030.6 34689.1 35188.0 35407.0 35715.8 36045.5 33608.7 33958.7 34219.5 34289.3 34583.1 34655.7 34656.0 34931.0 34941.7 36254.1 30338.4 32748.8 32916.3 32975.0 32975.8 24684.9 25198.6 25381.0 25780.6 25800.2 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | [#2] jquery.html Cycles:180 Average:25344.03 Median:40784.29 stddev:66667.38 (163.5%) stddev-sans-first:66828.02 19:26:41 INFO - PROCESS | 4265 | Values: 364.9 374.7 376.5 381.8 387.9 15759.1 16590.2 16650.2 16743.2 16757.7 15657.0 16047.6 16383.1 16470.0 16510.7 5001.5 5229.9 5234.1 5235.1 5242.4 3678.7 3871.8 3872.4 3929.0 5132.7 6209.3 6210.2 6214.8 6216.8 6218.4 5996.1 6049.5 6137.5 6192.4 6200.0 5381.0 5385.9 5391.4 5391.6 5403.3 19872.0 20218.8 20342.1 20563.0 20655.0 1889.1 1973.2 2073.6 2153.9 2346.8 12568.4 13312.1 13583.6 13616.0 13779.5 1708.2 1767.6 1768.4 1773.2 1827.3 68559.0 68817.3 69068.7 70115.3 71430.6 9463.8 9949.7 9950.4 10096.2 10274.6 13439.6 13452.8 13460.8 13479.6 13502.5 7389.0 7481.0 7625.5 7661.7 7681.2 7059.5 7113.6 7150.1 7295.6 7319.6 6127.3 6136.6 6174.7 6202.8 6227.1 1704.9 1744.4 1751.1 1764.7 1777.5 825.5 873.0 882.2 886.5 887.4 17228.4 17241.6 17738.7 17969.6 18279.5 816.5 844.6 865.0 868.2 920.1 9786.0 9972.6 10002.1 10179.3 10218.6 396799.0 398546.0 398632.0 404636.0 412887.0 85135.0 86351.1 89466.0 90316.5 91630.0 874.5 911.7 945.5 949.3 952.8 14591.9 15545.6 16178.2 16246.6 16512.0 38873.8 40094.0 40345.8 40523.0 41045.6 50922.7 52698.2 55978.0 56607.7 57666.4 7636.3 7707.9 7764.1 7847.1 8051.4 9232.4 11497.5 11589.7 11784.9 11956.8 9486.6 9740.5 10113.6 10218.2 10330.5 9738.2 9999.9 10079.2 10257.7 10481.7 10422.6 10587.8 10685.1 10902.8 11029.8 6653.6 6912.7 6932.6 7419.7 7740.6 8743.4 10179.4 10274.7 10298.7 10759.3 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | [#3] mootools.html Cycles:180 Average:5187.46 Median:4803.34 stddev:3015.32 (62.8%) stddev-sans-first:3008.99 19:26:41 INFO - PROCESS | 4265 | Values: 1212.5 1218.0 1255.4 1300.7 1682.6 7482.1 7872.0 7909.3 8156.6 8187.0 7980.3 8089.1 8108.0 8111.4 8120.7 2159.4 2179.1 2181.7 2182.4 2183.2 2103.1 2171.9 2174.9 2175.8 2180.9 2148.2 2155.6 2158.0 2159.3 2194.2 2110.0 2111.8 2112.7 2112.9 2114.5 2103.9 2152.3 2152.5 2153.1 2159.9 7744.8 8030.3 8059.8 8134.8 8137.7 4396.6 4616.3 4617.1 4628.0 4631.2 2718.6 2856.5 2894.2 2912.8 2921.3 941.4 943.2 947.9 958.6 961.3 11027.2 11081.1 11097.1 11120.3 11124.1 4023.9 4067.9 4165.8 4190.6 4240.1 8651.2 8711.7 8719.6 8721.6 8721.7 4634.7 4641.4 4650.2 4653.0 4659.1 3661.8 4052.6 4237.7 4281.4 4307.7 3345.4 3371.4 3481.6 3490.5 3497.1 983.3 999.2 1009.8 1018.5 1026.7 696.5 741.9 784.6 818.9 826.5 5084.8 5231.4 5243.6 5347.1 5363.7 408.6 410.1 410.2 413.6 417.1 3451.4 3537.1 3540.7 3575.3 3659.5 9617.5 10076.9 10091.3 10098.1 10104.6 9652.2 9983.9 10001.1 10002.5 10004.1 7466.0 7506.1 7520.7 7574.1 7576.1 5122.0 5137.4 5229.6 5293.6 5518.8 9179.9 9233.2 9282.4 9291.7 9358.1 9249.4 9255.3 9538.9 9539.0 9557.9 5758.0 6232.5 6382.8 6441.6 6463.2 7520.4 7695.1 7792.4 7793.3 7794.3 6947.8 6990.6 6997.0 7003.5 7240.0 6783.8 6870.1 6896.0 6907.4 7012.2 6231.2 6280.3 6280.8 6333.7 6338.0 4581.9 4668.6 4703.6 4896.2 4936.9 4610.1 4660.9 4771.0 4835.7 4870.8 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | [#4] prototype.html Cycles:180 Average:4023.61 Median:4419.60 stddev:1155.90 (26.2%) stddev-sans-first:1124.75 19:26:41 INFO - PROCESS | 4265 | Values: 294.9 298.0 303.2 307.5 311.2 4572.2 4752.8 4793.4 4795.4 4839.0 4577.7 4674.2 4674.6 4676.4 4688.2 3764.7 3768.6 3769.6 3771.6 3773.3 3587.2 3671.2 3770.0 3771.8 3776.9 3723.5 3739.6 3740.6 3748.6 3761.9 3734.9 3737.9 3739.3 3741.6 3744.9 3327.4 3330.3 3333.5 3336.7 3344.7 4453.2 4583.9 4591.4 4617.7 4623.0 4253.3 4255.3 4262.3 4262.6 4263.2 4921.5 4925.5 4925.5 4926.9 4941.9 3310.0 3503.4 3623.9 3631.8 3641.2 5077.9 5127.5 5128.8 5148.6 5152.3 3559.8 3564.9 3567.8 3582.7 3586.3 3613.2 3631.3 3634.9 3637.2 3638.3 3970.4 4131.6 4134.8 4178.1 4195.9 4919.6 4925.0 4925.7 4927.7 4927.9 1188.8 1233.5 1254.0 1254.8 1269.4 842.8 857.5 870.8 875.3 881.6 4413.4 4413.5 4420.6 4434.0 4445.4 5022.3 5038.1 5046.6 5074.4 5096.9 4408.2 4418.6 4422.2 4433.0 4443.8 2299.3 2375.0 2387.0 2401.9 2404.3 4247.3 4261.9 4265.6 4270.3 4275.5 4567.1 4581.7 4589.7 4597.3 4604.9 4036.4 4227.9 4240.3 4255.3 4265.0 4456.7 4474.8 4474.9 4508.7 4517.2 4409.0 4410.3 4412.7 4414.9 4429.9 4270.7 4277.3 4304.5 4476.3 4829.4 3557.9 3568.6 3588.9 3592.6 3593.5 5108.2 5119.6 5123.9 5125.7 5129.8 4751.4 4850.5 4953.9 4958.8 4966.7 4942.5 4942.9 4954.8 4967.9 4974.4 4879.3 4914.6 4933.5 4941.4 4942.0 4776.7 4998.4 5036.1 5036.3 5043.6 4885.5 4928.1 4928.5 4937.9 4941.4 19:26:41 INFO - PROCESS | 4265 | 19:26:41 INFO - PROCESS | 4265 | [#5] yui.html Cycles:180 Average:17819.29 Median:22599.37 stddev:26426.21 (116.9%) stddev-sans-first:26468.79 19:26:41 INFO - PROCESS | 4265 | Values: 622.3 660.7 681.7 713.7 717.0 12663.9 13607.7 13690.5 13717.7 14042.5 12096.4 12112.3 12113.0 12119.1 12160.5 584.9 674.0 713.7 714.9 735.5 729.8 737.0 738.5 740.9 741.8 778.2 780.1 781.2 782.0 782.1 599.5 740.6 774.9 776.7 776.8 569.3 569.8 570.0 570.4 570.6 12530.3 12546.4 12550.1 12557.3 12560.8 12126.3 12304.2 12337.6 12373.9 12506.5 11464.5 11494.7 11661.9 11790.8 12110.6 3893.2 3956.6 3970.6 3972.6 4010.7 65889.0 67686.7 67817.0 67956.7 67978.0 9807.6 10089.7 10272.1 10468.2 10524.8 18592.9 18675.6 18757.2 18812.8 18872.7 6595.8 6624.9 6694.4 6695.4 6742.3 3623.7 3630.2 3651.8 3731.5 3751.7 2834.9 2926.7 2930.6 2931.1 2932.1 482.3 484.4 484.9 496.1 503.5 512.5 514.6 521.9 534.1 538.2 7007.8 7258.0 7261.2 7320.7 7494.7 439.1 495.6 521.5 536.4 537.0 3473.3 3658.8 3714.0 3725.5 3763.6 103892.5 106060.5 106148.5 106558.5 108455.0 82668.2 84353.6 84818.6 89690.6 89770.6 72844.0 81168.2 81199.0 81510.0 81655.6 6689.5 7060.6 7144.5 7182.7 7271.8 31510.4 31744.2 36247.0 41188.0 42978.2 41407.8 52398.5 60059.7 60116.4 61867.4 11047.8 11099.6 11227.1 11465.9 11860.7 11814.6 12207.0 12364.9 13149.6 13163.3 11623.6 11794.1 12015.8 12160.5 12538.1 10838.7 11228.4 11318.4 11750.4 11891.1 10775.2 11396.9 11399.5 12100.6 12386.1 9789.3 9878.9 10116.1 10171.8 10333.9 8125.9 8417.8 8588.0 8969.5 10053.1 19:26:41 INFO - PROCESS | 4265 | -------- Summary: end -------- 19:26:41 INFO - PROCESS | 4265 | 19:26:42 INFO - TEST-INFO | 4265: exit 0 19:26:42 INFO - TEST-OK | dromaeo_css | took 1122209ms 19:26:42 INFO - TEST-START | kraken 19:26:42 INFO - operating with platform_type : linux_ 19:26:42 INFO - Initialising browser for kraken test... 19:26:42 INFO - TEST-INFO | started process 4570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2TkCpt/profile http://localhost:38212/getInfo.html) 19:26:47 INFO - PROCESS | 4570 | __metrics Screen width/height:1600/1200 19:26:47 INFO - PROCESS | 4570 | colorDepth:24 19:26:47 INFO - PROCESS | 4570 | Browser inner width/height: 1024/697 19:26:47 INFO - PROCESS | 4570 | __metrics 19:26:48 INFO - PROCESS | 4570 | 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" 19:26:48 INFO - PROCESS | 4570 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:26:49 INFO - PROCESS | 4570 | console.error: PushService: 19:26:49 INFO - PROCESS | 4570 | stateChangeProcessEnqueue: Error transitioning state 19:26:49 INFO - PROCESS | 4570 | UnknownError 19:26:49 INFO - TEST-INFO | 4570: exit 0 19:26:49 INFO - Browser initialized. 19:26:49 INFO - Running cycle 1/1 for kraken test... 19:26:49 INFO - TEST-INFO | started process 4654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2TkCpt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:01 INFO - PROCESS | 4654 | RSS: Main: 181157888 19:27:01 INFO - PROCESS | 4654 | 19:27:04 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38212/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:05 INFO - PROCESS | 4654 | RSS: Main: 235384832 19:27:05 INFO - PROCESS | 4654 | 19:27:08 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38212/tests/kraken/driver.html?testName=audio-dft) 19:27:09 INFO - PROCESS | 4654 | RSS: Main: 224645120 19:27:09 INFO - PROCESS | 4654 | 19:27:12 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38212/tests/kraken/driver.html?testName=audio-fft) 19:27:12 INFO - PROCESS | 4654 | RSS: Main: 303640576 19:27:12 INFO - PROCESS | 4654 | 19:27:15 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38212/tests/kraken/driver.html?testName=audio-oscillator) 19:27:15 INFO - PROCESS | 4654 | RSS: Main: 213942272 19:27:15 INFO - PROCESS | 4654 | 19:27:17 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38212/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:27:18 INFO - PROCESS | 4654 | RSS: Main: 214441984 19:27:18 INFO - PROCESS | 4654 | 19:27:21 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38212/tests/kraken/driver.html?testName=imaging-darkroom) 19:27:22 INFO - PROCESS | 4654 | RSS: Main: 253222912 19:27:22 INFO - PROCESS | 4654 | 19:27:25 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38212/tests/kraken/driver.html?testName=imaging-desaturate) 19:27:26 INFO - PROCESS | 4654 | RSS: Main: 247312384 19:27:26 INFO - PROCESS | 4654 | 19:27:29 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38212/tests/kraken/driver.html?testName=json-parse-financial) 19:27:29 INFO - PROCESS | 4654 | RSS: Main: 250605568 19:27:29 INFO - PROCESS | 4654 | 19:27:31 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38212/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:27:32 INFO - PROCESS | 4654 | RSS: Main: 214953984 19:27:32 INFO - PROCESS | 4654 | 19:27:34 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:27:34 INFO - PROCESS | 4654 | RSS: Main: 221872128 19:27:34 INFO - PROCESS | 4654 | 19:27:36 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:27:37 INFO - PROCESS | 4654 | RSS: Main: 222670848 19:27:37 INFO - PROCESS | 4654 | 19:27:40 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:27:41 INFO - PROCESS | 4654 | RSS: Main: 223748096 19:27:41 INFO - PROCESS | 4654 | 19:27:44 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:27:44 INFO - PROCESS | 4654 | RSS: Main: 221057024 19:27:44 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | Cycle 1(1): loaded http://localhost:38212/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38212/tests/kraken/driver.html?testName=ai-astar) 19:27:46 INFO - PROCESS | 4654 | RSS: Main: 272498688 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | __start_tp_report 19:27:46 INFO - PROCESS | 4654 | _x_x_mozilla_page_load 19:27:46 INFO - PROCESS | 4654 | _x_x_mozilla_page_load_details 19:27:46 INFO - PROCESS | 4654 | |i|pagename|runs| 19:27:46 INFO - PROCESS | 4654 | |0;ai-astar;117;117;120;116;114;117;115;114;116;132 19:27:46 INFO - PROCESS | 4654 | |1;audio-beat-detection;153;123;148;183;127;146;150;125;182;124 19:27:46 INFO - PROCESS | 4654 | |2;audio-dft;161;161;162;161;161;159;161;161;161;160 19:27:46 INFO - PROCESS | 4654 | |3;audio-fft;75;75;75;169;76;78;76;75;167;76 19:27:46 INFO - PROCESS | 4654 | |4;audio-oscillator;84;85;158;84;85;85;138;84;85;139 19:27:46 INFO - PROCESS | 4654 | |5;imaging-gaussian-blur;118;117;117;117;117;117;118;118;119;118 19:27:46 INFO - PROCESS | 4654 | |6;imaging-darkroom;148;147;146;146;148;148;146;146;147;146 19:27:46 INFO - PROCESS | 4654 | |7;imaging-desaturate;96;96;96;95;95;95;95;96;95;94 19:27:46 INFO - PROCESS | 4654 | |8;json-parse-financial;66;66;66;67;68;68;67;66;67;70 19:27:46 INFO - PROCESS | 4654 | |9;json-stringify-tinderbox;66;67;66;66;67;66;66;66;66;67 19:27:46 INFO - PROCESS | 4654 | |10;stanford-crypto-aes;84;81;82;82;83;82;82;82;82;88 19:27:46 INFO - PROCESS | 4654 | |11;stanford-crypto-ccm;152;143;137;143;133;135;136;146;177;141 19:27:46 INFO - PROCESS | 4654 | |12;stanford-crypto-pbkdf2;179;176;181;180;176;178;178;176;183;176 19:27:46 INFO - PROCESS | 4654 | |13;stanford-crypto-sha256-iterative;70;71;70;71;69;70;71;68;68;68 19:27:46 INFO - PROCESS | 4654 | __end_tp_report 19:27:46 INFO - PROCESS | 4654 | __start_cc_report 19:27:46 INFO - PROCESS | 4654 | _x_x_mozilla_cycle_collect,1945 19:27:46 INFO - PROCESS | 4654 | __end_cc_report 19:27:46 INFO - PROCESS | 4654 | __startTimestamp1472783266773__endTimestamp 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | ------- Summary: start ------- 19:27:46 INFO - PROCESS | 4654 | Number of tests: 14 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#0] ai-astar Cycles:10 Average:117.80 Median:117.00 stddev:5.29 (4.5%) stddev-sans-first:5.60 19:27:46 INFO - PROCESS | 4654 | Values: 117.0 117.0 120.0 116.0 114.0 117.0 115.0 114.0 116.0 132.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:149.00 stddev:22.48 (15.1%) stddev-sans-first:23.71 19:27:46 INFO - PROCESS | 4654 | Values: 153.0 123.0 148.0 183.0 127.0 146.0 150.0 125.0 182.0 124.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 19:27:46 INFO - PROCESS | 4654 | Values: 161.0 161.0 162.0 161.0 161.0 159.0 161.0 161.0 161.0 160.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#3] audio-fft Cycles:10 Average:94.20 Median:75.50 stddev:38.91 (51.5%) stddev-sans-first:40.64 19:27:46 INFO - PROCESS | 4654 | Values: 75.0 75.0 75.0 169.0 76.0 78.0 76.0 75.0 167.0 76.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:84.50 stddev:29.67 (35.1%) stddev-sans-first:30.69 19:27:46 INFO - PROCESS | 4654 | Values: 84.0 85.0 158.0 84.0 85.0 85.0 138.0 84.0 85.0 139.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 19:27:46 INFO - PROCESS | 4654 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 119.0 118.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 19:27:46 INFO - PROCESS | 4654 | Values: 148.0 147.0 146.0 146.0 148.0 148.0 146.0 146.0 147.0 146.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 19:27:46 INFO - PROCESS | 4654 | Values: 96.0 96.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:67.00 stddev:1.29 (1.9%) stddev-sans-first:1.30 19:27:46 INFO - PROCESS | 4654 | Values: 66.0 66.0 66.0 67.0 68.0 68.0 67.0 66.0 67.0 70.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 19:27:46 INFO - PROCESS | 4654 | Values: 66.0 67.0 66.0 66.0 67.0 66.0 66.0 66.0 66.0 67.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:82.00 stddev:1.99 (2.4%) stddev-sans-first:2.06 19:27:46 INFO - PROCESS | 4654 | Values: 84.0 81.0 82.0 82.0 83.0 82.0 82.0 82.0 82.0 88.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#11] stanford-crypto-ccm Cycles:10 Average:144.30 Median:143.00 stddev:12.83 (9.0%) stddev-sans-first:13.31 19:27:46 INFO - PROCESS | 4654 | Values: 152.0 143.0 137.0 143.0 133.0 135.0 136.0 146.0 177.0 141.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:178.50 stddev:2.45 (1.4%) stddev-sans-first:2.59 19:27:46 INFO - PROCESS | 4654 | Values: 179.0 176.0 181.0 180.0 176.0 178.0 178.0 176.0 183.0 176.0 19:27:46 INFO - PROCESS | 4654 | 19:27:46 INFO - PROCESS | 4654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:1.26 (1.8%) stddev-sans-first:1.33 19:27:46 INFO - PROCESS | 4654 | Values: 70.0 71.0 70.0 71.0 69.0 70.0 71.0 68.0 68.0 68.0 19:27:46 INFO - PROCESS | 4654 | -------- Summary: end -------- 19:27:46 INFO - PROCESS | 4654 | 19:27:47 INFO - TEST-INFO | 4654: exit 0 19:27:48 INFO - TEST-OK | kraken | took 65659ms 19:27:48 INFO - SUITE-END | took 1187s 19:27:48 INFO - Completed test suite (00:19:48) 19:27:48 INFO - javascript benchmark 19:27:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2592.027989503936, 2616.9869150654245, 2617.0447786557907, 2661.840289582624, 2665.426933209361, 4607.354970304152, 4643.628509719223, 4665.230237010897, 4701.50634183411, 4785.928211076836, 4757.785899634516, 4766.928496072553, 4792.185328494155, 4805.807767689288, 4852.636052296083, 4313.374560688699, 4348.934765978513, 4408.537103604123, 4474.6196573291145, 4498.640108791289, 4415.712978656377, 4454.599086082252, 4474.328850672393, 4493.123840851035, 4505.662075344346, 4273.594008569181, 4305.22505948929, 4348.391225228471, 4370.868873933787, 4418.80115394808, 4305.827766889321, 4334.393184954109, 4471.351654010183, 4474.753888536129, 4493.236149854532, 4331.112122014979, 4338.219120558329, 4348.260795664744, 4385.122975404932, 4391.165678521071, 4543.954560454419, 4741.2651039089, 4742.525747425264, 4743.739094349809, 4745.881352966169, 4923.113839508887, 4969.652124351297, 4970.403551573798, 4972.676776009548, 4978.278149668314, 4985.775640096168, 5004.649674522785, 5021.874453138665, 5095.694258344512, 5108.897822043576, 4582.198115329804, 4687.132880417065, 4688.062387522475, 4694.647901407408, 4717.056588682277, 5211.088059589557, 5279.155335146358, 5302.761375738101, 5303.28405665238, 5306.389765177054, 5003.5997120230295, 5008.073506401327, 5011.674241174385, 5014.749262536931, 5060.797568097235, 5104.693718376835, 5104.979004199175, 5112.667676601009, 5158.277841102249, 5177.611679124081, 4923.655344125877, 5008.098542262355, 5034.496550344937, 5077.669951453144, 5079.060373830853, 5076.416212135652, 5080.415752188472, 5084.898302034013, 5085.923711144335, 5123.001014802152, 4316.482022157361, 4317.438732964694, 4319.892002699958, 4359.956400436018, 4363.541828108003, 3719.683826874692, 3727.440883867387, 3750.156214851691, 3751.512303400595, 3779.7543159694533, 4396.450443694538, 4418.116376724668, 4454.621357184611, 4470.508244093147, 4472.105578884236, 5079.74601269935, 5120.462351453044, 5126.512981266774, 5130.1535246684625, 5135.6918584885025, 4321.0, 4376.846810361686, 4440.666949978765, 4445.110977804452, 4490.281554951257, 4575.405197324393, 4594.989102397467, 4596.080783843245, 4649.116667833168, 4669.066186762661, 4766.856994290177, 4801.01579176268, 4803.5916947059895, 4848.369711937426, 4853.538913803183, 4979.0788704089855, 5021.271915572149, 5054.21659642756, 5131.769070391767, 5161.690298581947, 4728.456227533878, 4738.952610473852, 4772.880677983078, 4803.903921921472, 4819.662623616313, 4994.450610432777, 4998.125328067501, 5000.024995125916, 5000.474950130184, 5065.848024559269, 4897.559219670246, 5065.4174769900455, 5075.492450754895, 5079.263506791568, 5143.92284115746, 4929.507049295042, 4962.776675049559, 4979.352684151182, 4999.575036121971, 5052.0, 4970.801167953241, 4982.003599280086, 4985.925211121764, 5000.324956130876, 5041.369828771403, 4746.905061898812, 4907.730074845917, 4922.237053256751, 4928.186849169847, 4989.027139707722, 4932.45742968266, 4954.925676114789, 4962.900741985212, 4983.925241121314, 5019.472955339783, 4875.634327425528, 4878.0, 4878.902421951612, 4882.414110306786, 4931.48219436954], "value": 4670.278965635932, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.3089192171902, 559.56074481532, 562.3701454371105, 563.2733773432273, 567.1322875999721, 27697.16908492746, 36919.446208306894, 38373.42439863397, 38486.80756596217, 42236.78881605595, 38715.61284387155, 39061.804690976576, 39121.804390978, 39151.60848391515, 39155.0, 7632.198619144993, 7780.377569794431, 7780.80547986302, 7786.377089832799, 7788.571628560427, 7245.239249878739, 9704.805903881917, 9978.550965206547, 9978.60085596579, 10321.793564128713, 9975.600975960953, 10051.798964020716, 10051.849222261671, 10064.094231519162, 10123.949380253089, 9871.40771553709, 9922.851157232648, 9936.205103591696, 9945.850812237823, 9948.651797187136, 9792.951035244778, 9882.851757223647, 9890.802183956352, 9900.90099099007, 9901.108900198944, 36652.083697907765, 36817.63182368169, 36935.63064369349, 36974.445383319005, 36981.260374792626, 2897.3046468847324, 3002.774791890617, 3103.131123285461, 3173.926407741965, 3274.4269752793166, 28021.299467513272, 28341.433171336663, 28484.145475635763, 28888.0, 28960.0, 3506.8596314775054, 3519.1378112362636, 3551.183227857607, 3594.658507441789, 3614.204874927512, 252225.0, 252560.0, 252882.0, 252915.0, 253130.0, 30104.849475752482, 31027.844860775553, 49121.0, 49470.752646236535, 49655.0, 32900.67099328976, 32943.176420589196, 33041.0, 33159.0, 33190.66809331924, 12361.258324500379, 12376.690582735504, 12386.071044671686, 12392.070594705434, 12397.876021239852, 9362.438253704799, 9570.61717531305, 11173.553057877756, 11298.378589177673, 11298.943505282421, 7289.635518224173, 7350.595717235603, 7470.244492544248, 7740.109887362913, 7778.494397864234, 2980.8956686516303, 3031.363413683107, 3050.8779648814243, 3072.2165847708693, 3076.0771768469817, 861.724248240557, 871.005215156079, 872.4024043530202, 886.7650072730731, 921.9354645174908, 26702.866485667837, 27203.86398067997, 27337.589936150973, 27607.30981725473, 28105.71894281072, 897.7261935109724, 899.4153800030033, 908.7728067983004, 941.8932754014006, 964.2141654551658, 13869.787710390541, 14537.41850325975, 14829.62925926839, 14851.925740371229, 14858.108513489226, 389072.0, 393961.0, 394166.0, 394234.0, 394561.0, 47438.52561474341, 47869.5213047872, 47954.520454795704, 47963.280550791766, 47967.760161198974, 1101.7465982823996, 1127.4700890581414, 1146.2606618730872, 1149.3333866357493, 1177.7879981603223, 25268.241952741446, 26402.73597264003, 26734.86632566825, 27174.592381114693, 27214.319642009108, 41287.0, 41554.0, 41596.1680766389, 41612.3758143622, 41667.0, 318048.0, 318739.0, 320417.0, 320637.0, 321444.0, 21755.0, 23860.88069559641, 24325.513489729754, 24983.125590604242, 25394.365140870887, 40361.5783053006, 40607.59392406156, 40702.79648601738, 40774.59225407708, 41030.58969410268, 34689.14351990673, 35188.0, 35407.0, 35715.82142089377, 36045.45931810972, 33608.66391336056, 33958.66041339555, 34219.4867076999, 34289.314213715086, 34583.13542161466, 34655.65344346524, 34656.0, 34931.0, 34941.650583493836, 36254.09364765902, 30338.393232134793, 32748.836255819522, 32916.341673166884, 32975.0, 32975.8351208252, 24684.876575617724, 25198.62202067007, 25381.0, 25780.613290800276, 25800.225993220232], "value": 16439.778438580954, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.92336609312025, 374.6665467733717, 376.54814222932487, 381.7766606535177, 387.9398693202553, 15759.133247671374, 16590.170491475423, 16650.16749162543, 16743.162841857888, 16757.664846703057, 15657.0, 16047.598810029755, 16383.09892955887, 16470.0, 16510.66978660426, 5001.474845141262, 5229.869253268661, 5234.057869583474, 5235.110031294689, 5242.423333433319, 3678.7057035437233, 3871.7870517121546, 3872.380419132939, 3929.0373858404732, 5132.717700526469, 6209.316975132731, 6210.161628180205, 6214.78811631732, 6216.751329946796, 6218.378162183768, 5996.0706090555595, 6049.516038716914, 6137.539684523678, 6192.442680158785, 6200.0, 5380.973095134538, 5385.919211211835, 5391.353037635509, 5391.56867450603, 5403.297571315743, 19872.0, 20218.797812021985, 20342.08460619276, 20563.0, 20655.0, 1889.1121173048782, 1973.2304401283511, 2073.5956488484753, 2153.881536515491, 2346.77705617966, 12568.371581420985, 13312.134711243834, 13583.592492225249, 13616.046876718634, 13779.517716879862, 1708.1544635405553, 1767.6111255523765, 1768.3722278591044, 1773.15775006872, 1827.3147569661378, 68559.0, 68817.31182688159, 69068.6546567264, 70115.29884701139, 71430.64284678543, 9463.823320636155, 9949.651762188289, 9950.35322704022, 10096.242781791396, 10274.640387586398, 13439.596812095653, 13452.798208026887, 13460.798088028687, 13479.595612131652, 13502.527411540548, 7388.963055184689, 7480.9625951870985, 7625.504342217738, 7661.731839385595, 7681.193474685189, 7059.470539709439, 7113.57318560888, 7150.070490836262, 7295.6352182390665, 7319.597422141834, 6127.295361033449, 6136.570440069241, 6174.746217630533, 6202.751889924442, 6227.1282020517165, 1704.9062301573533, 1744.3807448355906, 1751.1156865782953, 1764.6558921010537, 1777.5378401615674, 825.5005721538503, 873.0178549132227, 882.1751662195867, 886.4814083761072, 887.3744010472731, 17228.397006104977, 17241.56896077608, 17738.733918991227, 17969.640607187783, 18279.451616451526, 816.4570560577268, 844.5718559142465, 864.9754460184843, 868.1925808997693, 920.1396694091046, 9786.0, 9972.551235194434, 10002.149817265614, 10179.338343007681, 10218.591256349662, 396799.0, 398546.0, 398632.0, 404636.0, 412887.0, 85135.0, 86351.1364886343, 89466.0, 90316.5484172575, 91630.0, 874.4709450782312, 911.6900253913701, 945.5083356654501, 949.2738055387634, 952.8475443928938, 14591.92704036473, 15545.615832479481, 16178.191090445196, 16246.593835154215, 16512.0, 38873.80563097166, 40094.0, 40345.79827100963, 40523.0, 41045.58954410418, 50922.74538627283, 52698.20952685789, 55978.0, 56607.71696141493, 57666.42333576778, 7636.274553917369, 7707.88438173439, 7764.0683118023935, 7847.058352997677, 8051.4430985492845, 9232.353735238468, 11497.482613282255, 11589.6523104307, 11784.882151178379, 11956.820647690465, 9486.620535178516, 9740.512974351395, 10113.64602238918, 10218.182545396201, 10330.535125919201, 9738.172255358373, 9999.900001000187, 10079.193664506969, 10257.692269231935, 10481.737956551147, 10422.635207767995, 10587.841182382117, 10685.145188384755, 10902.78194436091, 11029.779404411707, 6653.567518111307, 6912.723491060301, 6932.61870597122, 7419.703211871465, 7740.612969351622, 8743.431676940978, 10179.440130792878, 10274.743131421774, 10298.742531436474, 10759.300645458334], "value": 8469.328493582534, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1212.5331747277298, 1217.9878201217984, 1255.378587599138, 1300.6618279247396, 1682.5793551612096, 7482.102147742265, 7872.0, 7909.288164065233, 8156.551389673566, 8186.97662792151, 7980.321672657831, 8089.110197878229, 8107.959460202691, 8111.391645626572, 8120.675172993073, 2159.406163305088, 2179.0847843905517, 2181.6727490876337, 2182.388931099295, 2183.235867446389, 2103.11669098978, 2171.945701357463, 2174.913003479859, 2175.8476906616543, 2180.9345719628436, 2148.2373757316163, 2155.644318687415, 2158.0, 2159.255057005331, 2194.221051526709, 2109.968350474744, 2111.778263282364, 2112.6725357569526, 2112.93661190163, 2114.4925217947657, 2103.9474013149643, 2152.3435352217566, 2152.4834039830484, 2153.1387445022115, 2159.8704077755383, 7744.845103097907, 8030.317423019051, 8059.758207253792, 8134.796630084295, 8137.715179968673, 4396.604305612478, 4616.330632058366, 4617.053504031665, 4627.976860115711, 4631.235846085426, 2718.592211168341, 2856.5429531275104, 2894.1751600793673, 2912.825230486156, 2921.2842853500815, 941.38809773647, 943.179433892511, 947.8530827721714, 958.5925981457852, 961.2550273537994, 11027.1729620278, 11081.058110060654, 11097.056750176245, 11120.277181983227, 11124.054455371303, 4023.879283621496, 4067.9389809152294, 4165.75005499671, 4190.580941905785, 4240.1307731914885, 8651.178138076873, 8711.695090671796, 8719.564021798884, 8721.563921803885, 8721.651133954698, 4634.72191668501, 4641.350210970467, 4650.162970665245, 4653.046125544255, 4659.138059458943, 3661.761985470936, 4052.5542190359038, 4237.724547904377, 4281.443412356373, 4307.65538756905, 3345.397828390913, 3371.359441706068, 3481.5822101347494, 3490.5287786148547, 3497.0557949353533, 983.2527279267808, 999.1557134221571, 1009.7778488732467, 1018.4856647393019, 1026.6509386808373, 696.4677018049775, 741.9035525381665, 784.6351446577255, 818.8894499242526, 826.5123577089546, 5084.771185296597, 5231.424543300234, 5243.580513558906, 5347.064263753828, 5363.651362661414, 408.57304117197003, 410.1080150672276, 410.1653135868493, 413.6194700875142, 417.05328903389136, 3451.3960056990427, 3537.1334023163977, 3540.6990405815277, 3575.2670702506443, 3659.505966694462, 9617.51912404377, 10076.949615251877, 10091.344062635904, 10098.14165795901, 10104.646337378157, 9652.22782177424, 9983.900160998297, 10001.14990225839, 10002.549885255181, 10004.149647279917, 7465.962670186723, 7506.061742282215, 7520.661570229244, 7574.053243344582, 7576.128745194263, 5122.0, 5137.434882162885, 5229.581633469389, 5293.550048245942, 5518.779248830162, 9179.862302065341, 9233.169014788702, 9282.396644218104, 9291.67479138227, 9358.064193580587, 9249.352545322032, 9255.25957923378, 9538.856917146388, 9539.0, 9557.856632150384, 5758.0, 6232.532560057924, 6382.776602818879, 6441.581297215666, 6463.192100987377, 7520.360769334668, 7695.076590809139, 7792.37660987131, 7793.29860312552, 7794.337481313925, 6947.826304342229, 6990.580565166107, 6996.965015175095, 7003.509754317149, 7240.0, 6783.762568310085, 6870.141232345957, 6896.034555162381, 6907.447404207752, 7012.158540975116, 6231.158793562811, 6280.27776805655, 6280.811575652738, 6333.746650133943, 6337.96831015842, 4581.862544123548, 4668.626509879134, 4703.647226457961, 4896.21660534327, 4936.925946110883, 4610.10103029906, 4660.906781864276, 4770.9980904010545, 4835.685680430761, 4870.78081486341], "value": 4045.5243424404703, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.90710426215725, 297.9925501862454, 303.1814101924803, 307.5218035954092, 311.15676516639905, 4572.222722137236, 4752.811797050737, 4793.424789025313, 4795.3766010418685, 4839.024000239853, 4577.725336479813, 4674.228752255874, 4674.6260299176065, 4676.368690226818, 4688.249880019198, 3764.6635444417248, 3768.5854555998953, 3769.6230376962358, 3771.641694039062, 3773.2642134783728, 3587.192881601645, 3671.192337685705, 3770.0, 3771.830267637949, 3776.924461510768, 3723.515942927429, 3739.6447337502896, 3740.5511338639403, 3748.5501739791125, 3761.9435708464393, 3734.9066273343383, 3737.8878633640898, 3739.2708421857747, 3741.6071312512204, 3744.906377340561, 3327.4028466336295, 3330.3015462114254, 3333.4833100869405, 3336.73306135511, 3344.715699165574, 4453.198424283628, 4583.862484125481, 4591.3572099905705, 4617.746023968681, 4622.95377046232, 4253.340732186516, 4255.2978758504805, 4262.339337402707, 4262.595053469915, 4263.232618128735, 4921.487933514451, 4925.507449255082, 4925.546963645697, 4926.852194434173, 4941.925871111936, 3309.9834500827583, 3503.404421248394, 3623.8912832615065, 3631.8184090795617, 3641.198936234051, 5077.857482066543, 5127.461616530248, 5128.794848206067, 5148.639595228336, 5152.330197074394, 3559.804210768381, 3564.946525802115, 3567.786952436184, 3582.7133829293593, 3586.30067136911, 3613.223157021253, 3631.291898079902, 3634.909127271839, 3637.1634524059614, 3638.2541578976247, 3970.3647416413237, 4131.628153466202, 4134.834606615702, 4178.143480586473, 4195.8741237762915, 4919.557239848359, 4924.9507504925205, 4925.655204135676, 4927.655064145475, 4927.852164435073, 1188.7563049574817, 1233.5065973610629, 1253.9874601254053, 1254.7553227120625, 1269.3843485909326, 842.8019415437367, 857.5455008845322, 870.7504730711323, 875.2953872132994, 881.5724373678761, 4413.426254586947, 4413.470383553994, 4420.646348292129, 4433.977830110829, 4445.355423463579, 5022.271770593244, 5038.077914809475, 5046.596272298208, 5074.447886479479, 5096.949030509648, 4408.206522825923, 4418.558144185556, 4422.159789639958, 4433.0, 4443.844465443759, 2299.2987138922454, 2374.952500949971, 2386.9880650596983, 2401.9159329423387, 2404.2546810488802, 4247.299195632748, 4261.893452663646, 4265.573442655772, 4270.338097594878, 4275.486941567032, 4567.10941366437, 4581.679282450196, 4589.65577581676, 4597.310403439511, 4604.88487787808, 4036.3945408189006, 4227.9365809512265, 4240.307404797975, 4255.340422234559, 4265.0, 4456.68803183774, 4474.79863406141, 4474.9328760069275, 4508.729476231437, 4517.221908526299, 4408.977955110204, 4410.33844923264, 4412.669049821212, 4414.889627759332, 4429.867103986886, 4270.676090411982, 4277.251480990876, 4304.461942257218, 4476.2837945928495, 4829.44461386945, 3557.8932632021083, 3568.626078959567, 3588.8743893963588, 3592.5868525119945, 3593.460980852893, 5108.208227724708, 5119.6416250862085, 5123.92314115296, 5125.74371281427, 5129.846104616868, 4751.382320298478, 4850.53919877611, 4953.851384458472, 4958.752062396938, 4966.652334336562, 4942.456329803646, 4942.901141977069, 4954.752262386938, 4967.875803104951, 4974.3584617077, 4879.316895634685, 4914.606831453547, 4933.531314525115, 4941.382327209099, 4942.0, 4776.665633405628, 4998.350214472095, 5036.053908463444, 5036.269740887477, 5043.5712964398435, 4885.5358740919555, 4928.112939670894, 4928.482509336612, 4937.851864444073, 4941.431735350337], "value": 3662.379358761229, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.3185611755127, 660.659760223485, 681.7273090763691, 713.700245896723, 716.953398029128, 12663.873361266385, 13607.65980850478, 13690.452381904724, 13717.65705857354, 14042.50851220205, 12096.395180240976, 12112.27326360419, 12113.032307084777, 12119.091068169879, 12160.513579456834, 584.8830233953227, 673.989016475287, 713.7252157919213, 714.8534550417178, 735.4667865797308, 729.7737701312584, 737.0234439367832, 738.4867517075635, 740.9481336306461, 741.8293792427745, 778.1751343575828, 780.1223623423648, 781.2421950707804, 782.0459039971227, 782.1083964280724, 599.547341756975, 740.6074780366414, 774.8760198368291, 776.7164984780543, 776.8174478997431, 569.3281927325754, 569.7863301262026, 569.9544036477114, 570.3640440908357, 570.5863249144379, 12530.310832904186, 12546.435410406553, 12550.121491495598, 12557.309347985856, 12560.81158782619, 12126.27242365461, 12304.200226985216, 12337.568185113478, 12373.946557804715, 12506.49974001039, 11464.541418343257, 11494.712632184179, 11661.941690291578, 11790.823137652942, 12110.636680899586, 3893.1629699614437, 3956.6043395660204, 3970.563238043813, 3972.602739726033, 4010.7393019453934, 65889.01166482507, 67686.66156669185, 67817.0, 67956.6602166986, 67978.0, 9807.558659860324, 10089.747756306004, 10272.126869216201, 10468.162546996222, 10524.842127368096, 18592.907035464737, 18675.626487470447, 18757.155927983273, 18812.81187188138, 18872.716909246374, 6595.835104122435, 6624.86750264992, 6694.397504224643, 6695.4308883745425, 6742.258351581322, 3623.6919861811516, 3630.183208778004, 3651.8174091295327, 3731.4962480065387, 3751.718621103428, 2834.8724307406214, 2926.678065412803, 2930.6483222013494, 2931.0620601407345, 2932.105707759154, 482.2669542295761, 484.38241242415495, 484.9078675051729, 496.08967544555424, 503.4889587069128, 512.4619149892598, 514.5626217714912, 521.8538809133448, 534.1080395739125, 538.2142072574007, 7007.789766307019, 7258.0, 7261.194216217587, 7320.743773967992, 7494.70021199146, 439.1239477242857, 495.56142813609495, 521.5123859191668, 536.3590509341377, 536.9946300537023, 3473.3400653875683, 3658.7804731716183, 3713.962860371362, 3725.4970578971493, 3763.5671897731563, 103892.48053759834, 106060.46969765256, 106148.46925765477, 106558.46720766502, 108455.0, 82668.17331826725, 84353.57823210844, 84818.57590712007, 89690.55154724185, 89770.55114724515, 72844.0, 81168.18831811607, 81199.0, 81510.0, 81655.591722041, 6689.531732778659, 7060.646967651494, 7144.4641651877355, 7182.676779544829, 7271.818204544928, 31510.369792604477, 31744.206394840312, 36247.0, 41188.0, 42978.18100022519, 41407.792961035004, 52398.47601523936, 60059.69970150121, 60116.39883601108, 61867.381326186165, 11047.779044419227, 11099.611513596985, 11227.101831853595, 11465.94267028693, 11860.703482413008, 11814.637072585689, 12207.0, 12364.876351236373, 13149.60551183466, 13163.276019819003, 11623.59317423886, 11794.056475482112, 12015.75968480643, 12160.513579456723, 12538.122331436713, 10838.729031774268, 11228.438578071227, 11318.377489238172, 11750.412479376168, 11891.10816688769, 10775.245732798629, 11396.94301528487, 11399.544018239178, 12100.576479823163, 12386.132970691964, 9789.31474796786, 9878.901210988086, 10116.145755445714, 10171.847422288523, 10333.948330258301, 8125.878111828446, 8417.789555261168, 8587.95706021466, 8969.461832290084, 10053.145482634058], "value": 6371.913722169814, "unit": "score"}], "name": "dromaeo_css", "value": 6280.934744099473, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 120.0, 116.0, 114.0, 117.0, 115.0, 114.0, 116.0, 132.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 148.0, 183.0, 127.0, 146.0, 150.0, 125.0, 182.0, 124.0], "value": 146.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 161.0, 159.0, 161.0, 161.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 169.0, 76.0, 78.0, 76.0, 75.0, 167.0, 76.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 158.0, 84.0, 85.0, 85.0, 138.0, 84.0, 85.0, 139.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 119.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 146.0, 148.0, 148.0, 146.0, 146.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 68.0, 68.0, 67.0, 66.0, 67.0, 70.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 82.0, 83.0, 82.0, 82.0, 82.0, 82.0, 88.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 143.0, 137.0, 143.0, 133.0, 135.0, 136.0, 146.0, 177.0, 141.0], "value": 144.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 176.0, 181.0, 180.0, 176.0, 178.0, 178.0, 176.0, 183.0, 176.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 70.0, 71.0, 69.0, 70.0, 71.0, 68.0, 68.0, 68.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1589.6999999999996, "alertThreshold": 2.0}]} 19:27:48 INFO - Return code: 0 19:27:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:27:48 INFO - # TBPL SUCCESS # 19:27:48 INFO - Running post-action listener: _resource_record_post_action 19:27:48 INFO - [mozharness: 2016-09-02 02:27:48.596009Z] Finished run-tests step (success) 19:27:48 INFO - Running post-run listener: _resource_record_post_run 19:27:48 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 20480; Write bytes: 404992000; Read time: 484; Write time: 627616 19:27:48 INFO - TinderboxPrint: CPU usage
13.1% 19:27:48 INFO - TinderboxPrint: I/O read bytes / time
20,480 / 484 19:27:48 INFO - TinderboxPrint: I/O write bytes / time
404,992,000 / 627,616 19:27:48 INFO - TinderboxPrint: CPU idle
8,354.6 (86.9%) 19:27:48 INFO - TinderboxPrint: CPU user
1,205.3 (12.5%) 19:27:48 INFO - TinderboxPrint: Swap in / out
0 / 0 19:27:48 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 114778112; Read time: 272; Write time: 323192 19:27:48 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 290213888; Read time: 204; Write time: 304424 19:27:49 INFO - Running post-run listener: _upload_blobber_files 19:27:49 INFO - Blob upload gear active. 19:27:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:27:49 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 19:27:49 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:27:49 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:27:49 INFO - (blobuploader) - INFO - Open directory for files ... 19:27:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:27:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:27:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:53 INFO - (blobuploader) - INFO - Done attempting. 19:27:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:27:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:27:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:54 INFO - (blobuploader) - INFO - Done attempting. 19:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:55 INFO - (blobuploader) - INFO - Done attempting. 19:27:55 INFO - (blobuploader) - INFO - Iteration through files over. 19:27:55 INFO - Return code: 0 19:27:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:27:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:27:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76e29d84fb14dfa771447c612a0b9760527fe3720b4769d8c324db17ebcad1d6e6eefb367777fa35172281f7da237c4b3836a857b915beaf01a9678e645a6430", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/602d92525e3104aab34510cbae9ce969b58a934ca4d15ac77d5fa2dd3b1d457c6d13b75fe413677ef44d1fb77e2e48a86f0d071fe019ca5087a0fe3e13a5b08e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/241af75f7adcbe4ed9e4222d136a76f8a1905347f1aa61e7b510e9b2f07d37933ff6ae32b4bfc704005485768fcb95e251f130a2be93a0eb1e58a78446b42638"} 19:27:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:27:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:27:55 INFO - Contents: 19:27:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76e29d84fb14dfa771447c612a0b9760527fe3720b4769d8c324db17ebcad1d6e6eefb367777fa35172281f7da237c4b3836a857b915beaf01a9678e645a6430", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/602d92525e3104aab34510cbae9ce969b58a934ca4d15ac77d5fa2dd3b1d457c6d13b75fe413677ef44d1fb77e2e48a86f0d071fe019ca5087a0fe3e13a5b08e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/241af75f7adcbe4ed9e4222d136a76f8a1905347f1aa61e7b510e9b2f07d37933ff6ae32b4bfc704005485768fcb95e251f130a2be93a0eb1e58a78446b42638"} 19:27:55 INFO - Running post-run listener: copy_logs_to_upload_dir 19:27:55 INFO - Copying logs to upload dir... 19:27:55 INFO - mkdir: /builds/slave/test/build/upload/logs 19:27:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.615327 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-09-01 19:27:55.355302) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 19:27:55.358528) ========= 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=df17445c3590ff7897617a0e00000225-1472780962.49334-283195181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76e29d84fb14dfa771447c612a0b9760527fe3720b4769d8c324db17ebcad1d6e6eefb367777fa35172281f7da237c4b3836a857b915beaf01a9678e645a6430", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/602d92525e3104aab34510cbae9ce969b58a934ca4d15ac77d5fa2dd3b1d457c6d13b75fe413677ef44d1fb77e2e48a86f0d071fe019ca5087a0fe3e13a5b08e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/241af75f7adcbe4ed9e4222d136a76f8a1905347f1aa61e7b510e9b2f07d37933ff6ae32b4bfc704005485768fcb95e251f130a2be93a0eb1e58a78446b42638"} build_url:https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/TLfxt7vuT2iwW0Oqb2khtA/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/Firefox-Non-PGO/sha512/76e29d84fb14dfa771447c612a0b9760527fe3720b4769d8c324db17ebcad1d6e6eefb367777fa35172281f7da237c4b3836a857b915beaf01a9678e645a6430", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/602d92525e3104aab34510cbae9ce969b58a934ca4d15ac77d5fa2dd3b1d457c6d13b75fe413677ef44d1fb77e2e48a86f0d071fe019ca5087a0fe3e13a5b08e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/241af75f7adcbe4ed9e4222d136a76f8a1905347f1aa61e7b510e9b2f07d37933ff6ae32b4bfc704005485768fcb95e251f130a2be93a0eb1e58a78446b42638"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 19:27:55.383787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:27:55.384131) ========= 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=df17445c3590ff7897617a0e00000225-1472780962.49334-283195181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 19:27:55.465909) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 19:27:55.466247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 19:27:55.466527) ========= ========= Total master_lag: 0.17 =========