builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-064 starttime: 1472603352.03 results: success (0) buildid: 20160830165121 builduid: b7488877da28443983404f09cc9b87fd revision: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.025854) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.026375) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.026681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.041676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.042106) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 17:29:12-- 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% 44.0M=0s 2016-08-30 17:29:12 (44.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122100 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.178258) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.178599) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022790 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:12.228960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 17:29:12.229335) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0f4d2ef453c82d2499564402c0c3666ac834b5d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0f4d2ef453c82d2499564402c0c3666ac834b5d8 --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=a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 17:29:12,937 truncating revision to first 12 chars 2016-08-30 17:29:12,937 Setting DEBUG logging. 2016-08-30 17:29:12,937 attempt 1/10 2016-08-30 17:29:12,937 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0f4d2ef453c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 17:29:14,156 unpacking tar archive at: fx-team-0f4d2ef453c8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.491177 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 17:29:13.735228) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:13.735713) ========= script_repo_revision: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:13.736257) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:13.736625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 17:29:13.747773) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-08-30 17:29:13.748154) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:29:14 INFO - MultiFileLogger online at 20160830 17:29:14 in /builds/slave/test 17:29:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 17:29:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:29:14 INFO - {'append_to_log': False, 17:29:14 INFO - 'base_work_dir': '/builds/slave/test', 17:29:14 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:29:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:29:14 INFO - 'branch': 'Fx-Team-Non-PGO', 17:29:14 INFO - 'buildbot_json_path': 'buildprops.json', 17:29:14 INFO - 'config_files': ('talos/linux_config.py',), 17:29:14 INFO - 'default_actions': ('clobber', 17:29:14 INFO - 'read-buildbot-config', 17:29:14 INFO - 'download-and-extract', 17:29:14 INFO - 'populate-webroot', 17:29:14 INFO - 'create-virtualenv', 17:29:14 INFO - 'install', 17:29:14 INFO - 'run-tests'), 17:29:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:29:14 INFO - 'download_minidump_stackwalk': True, 17:29:14 INFO - 'download_symbols': 'ondemand', 17:29:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:29:14 INFO - 'tooltool.py': '/tools/tooltool.py', 17:29:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:29:14 INFO - '/tools/misc-python/virtualenv.py')}, 17:29:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:29:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:29:14 INFO - 'installer_path': 'installer.exe', 17:29:14 INFO - 'log_level': 'info', 17:29:14 INFO - 'log_name': 'talos', 17:29:14 INFO - 'log_to_console': True, 17:29:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:29:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:29:14 INFO - 'opt_config_files': (), 17:29:14 INFO - 'pip_index': False, 17:29:14 INFO - 'sps_profile': False, 17:29:14 INFO - 'sps_profile_interval': 0, 17:29:14 INFO - 'suite': 'dromaeojs', 17:29:14 INFO - 'system_bits': '32', 17:29:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:29:14 INFO - 'title': 'talos-linux64-ix-064', 17:29:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:29:14 INFO - 'use_talos_json': True, 17:29:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:29:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:29:14 INFO - 'work_dir': 'build'} 17:29:14 INFO - [mozharness: 2016-08-31 00:29:14.548708Z] Running clobber step. 17:29:14 INFO - Running pre-action listener: _resource_record_pre_action 17:29:14 INFO - Running main action method: clobber 17:29:14 INFO - rmtree: /builds/slave/test/build 17:29:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:29:15 INFO - Running post-action listener: _resource_record_post_action 17:29:15 INFO - [mozharness: 2016-08-31 00:29:15.006158Z] Finished clobber step (success) 17:29:15 INFO - [mozharness: 2016-08-31 00:29:15.006260Z] Running read-buildbot-config step. 17:29:15 INFO - Running pre-action listener: _resource_record_pre_action 17:29:15 INFO - Running main action method: read_buildbot_config 17:29:15 INFO - Using buildbot properties: 17:29:15 INFO - { 17:29:15 INFO - "project": "", 17:29:15 INFO - "product": "firefox", 17:29:15 INFO - "script_repo_revision": "production", 17:29:15 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:29:15 INFO - "repository": "", 17:29:15 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 17:29:15 INFO - "buildid": "20160830165121", 17:29:15 INFO - "pgo_build": "False", 17:29:15 INFO - "basedir": "/builds/slave/test", 17:29:15 INFO - "buildnumber": 3260, 17:29:15 INFO - "slavename": "talos-linux64-ix-064", 17:29:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:29:15 INFO - "platform": "ubuntu64_hw", 17:29:15 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:29:15 INFO - "branch": "fx-team", 17:29:15 INFO - "repo_path": "integration/fx-team", 17:29:15 INFO - "revision": "0f4d2ef453c82d2499564402c0c3666ac834b5d8", 17:29:15 INFO - "stage_platform": "linux64", 17:29:15 INFO - "builduid": "b7488877da28443983404f09cc9b87fd", 17:29:15 INFO - "slavebuilddir": "test" 17:29:15 INFO - } 17:29:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 17:29:15 INFO - Running post-action listener: _resource_record_post_action 17:29:15 INFO - [mozharness: 2016-08-31 00:29:15.008718Z] Finished read-buildbot-config step (success) 17:29:15 INFO - [mozharness: 2016-08-31 00:29:15.008809Z] Running download-and-extract step. 17:29:15 INFO - Running pre-action listener: _resource_record_pre_action 17:29:15 INFO - Running main action method: download_and_extract 17:29:15 INFO - mkdir: /builds/slave/test/build/tests 17:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:15 INFO - trying https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:29:15 INFO - Downloading https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:15 INFO - Downloaded 1361 bytes. 17:29:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 17:29:15 INFO - Using the following test package requirements: 17:29:15 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 17:29:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:29:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:29:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'jsshell-linux-x86_64.zip'], 17:29:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:29:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 17:29:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:29:15 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:29:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:29:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 17:29:15 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:29:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:29:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:15 INFO - trying https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:29:15 INFO - Downloading https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:16 INFO - Downloaded 20315467 bytes. 17:29:16 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 17:29:20 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 17:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:20 INFO - trying https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 17:29:20 INFO - Downloading https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:21 INFO - Downloaded 20315467 bytes. 17:29:21 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 17:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:26 INFO - trying https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 17:29:26 INFO - Downloading https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:27 INFO - Downloaded 14413998 bytes. 17:29:27 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 17:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:28 INFO - trying https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:28 INFO - Downloading https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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 17:29:29 INFO - Downloaded 55853970 bytes. 17:29:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:29 INFO - mkdir: /builds/slave/test/properties 17:29:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:29:29 INFO - Writing to file /builds/slave/test/properties/build_url 17:29:29 INFO - Contents: 17:29:29 INFO - build_url:https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 17:29:29 INFO - Running post-action listener: _resource_record_post_action 17:29:29 INFO - Running post-action listener: set_extra_try_arguments 17:29:29 INFO - [mozharness: 2016-08-31 00:29:29.988338Z] Finished download-and-extract step (success) 17:29:29 INFO - [mozharness: 2016-08-31 00:29:29.988565Z] Running populate-webroot step. 17:29:29 INFO - Running pre-action listener: _resource_record_pre_action 17:29:29 INFO - Running main action method: populate_webroot 17:29:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:29:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:29:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:29:29 INFO - '--tppagecycles', 17:29:29 INFO - '7'], 17:29:29 INFO - 'tests': ['tsvgm']}}, 17:29:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:29:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:29:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:29:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:29:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:29 INFO - 'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:29:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:29:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:29 INFO - 'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['damp', 'tps']}, 17:29:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:29 INFO - 'tests': ['damp', 'tps']}, 17:29:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['dromaeo_dom']}, 17:29:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:29:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['basic_compositor_video']}, 17:29:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:29:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:29:29 INFO - 'tests': ['a11yr', 17:29:29 INFO - 'ts_paint', 17:29:29 INFO - 'tpaint', 17:29:29 INFO - 'sessionrestore', 17:29:29 INFO - 'sessionrestore_no_auto_restore', 17:29:29 INFO - 'tabpaint']}, 17:29:29 INFO - 'other-e10s': {'tests': ['a11yr', 17:29:29 INFO - 'ts_paint', 17:29:29 INFO - 'tpaint', 17:29:29 INFO - 'sessionrestore', 17:29:29 INFO - 'sessionrestore_no_auto_restore', 17:29:29 INFO - 'tabpaint']}, 17:29:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:29:29 INFO - 'ts_paint', 17:29:29 INFO - 'tpaint', 17:29:29 INFO - 'sessionrestore', 17:29:30 INFO - 'sessionrestore_no_auto_restore', 17:29:30 INFO - 'tabpaint']}, 17:29:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:29:30 INFO - 'ts_paint', 17:29:30 INFO - 'tpaint', 17:29:30 INFO - 'sessionrestore', 17:29:30 INFO - 'sessionrestore_no_auto_restore', 17:29:30 INFO - 'tabpaint']}, 17:29:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:29:30 INFO - 'tests': ['a11yr', 17:29:30 INFO - 'ts_paint', 17:29:30 INFO - 'tpaint', 17:29:30 INFO - 'sessionrestore', 17:29:30 INFO - 'sessionrestore_no_auto_restore', 17:29:30 INFO - 'tabpaint']}, 17:29:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:29:30 INFO - 'tests': ['a11yr', 17:29:30 INFO - 'ts_paint', 17:29:30 INFO - 'tpaint', 17:29:30 INFO - 'sessionrestore', 17:29:30 INFO - 'sessionrestore_no_auto_restore', 17:29:30 INFO - 'tabpaint']}, 17:29:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:29:30 INFO - 'tests': ['tsvgx', 17:29:30 INFO - 'tsvgr_opacity', 17:29:30 INFO - 'tart', 17:29:30 INFO - 'tscrollx', 17:29:30 INFO - 'cart']}, 17:29:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:29:30 INFO - 'tsvgr_opacity', 17:29:30 INFO - 'tart', 17:29:30 INFO - 'tscrollx', 17:29:30 INFO - 'cart']}, 17:29:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:30 INFO - 'talos_options': ['--disable-e10s'], 17:29:30 INFO - 'tests': ['tp5o']}, 17:29:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:30 INFO - 'tests': ['tp5o']}, 17:29:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:30 INFO - 'talos_options': ['--disable-e10s', 17:29:30 INFO - '--xperf_path', 17:29:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:29:30 INFO - 'tests': ['tp5n']}, 17:29:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:29:30 INFO - 'talos_options': ['--xperf_path', 17:29:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:29:30 INFO - 'tests': ['tp5n']}}, 17:29:30 INFO - 'talos.zip': {'path': '', 17:29:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:29:30 INFO - Running post-action listener: _resource_record_post_action 17:29:30 INFO - [mozharness: 2016-08-31 00:29:30.003744Z] Finished populate-webroot step (success) 17:29:30 INFO - [mozharness: 2016-08-31 00:29:30.003833Z] Running create-virtualenv step. 17:29:30 INFO - Running pre-action listener: _resource_record_pre_action 17:29:30 INFO - Running main action method: create_virtualenv 17:29:30 INFO - Creating virtualenv /builds/slave/test/build/venv 17:29:30 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 17:29:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:29:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:29:30 INFO - Using real prefix '/usr' 17:29:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:29:30 INFO - Installing distribute.............................................................................................................................................................................................done. 17:29:32 INFO - Installing pip.................done. 17:29:32 INFO - Return code: 0 17:29:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:29: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')]} 17:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29: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')]} 17:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29: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', 'pip>=1.5'] in /builds/slave/test/build 17:29: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 pip>=1.5 17:29:32 INFO - Using env: {'DISPLAY': ':0', 17:29:32 INFO - 'HOME': '/home/cltbld', 17:29:32 INFO - 'LANG': 'en_US.UTF-8', 17:29:32 INFO - 'LANGUAGE': 'en_US:en', 17:29:32 INFO - 'LOGNAME': 'cltbld', 17:29:32 INFO - 'MAIL': '/var/mail/cltbld', 17:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:32 INFO - 'MOZ_NO_REMOTE': '1', 17:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:32 INFO - 'NO_EM_RESTART': '1', 17:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:32 INFO - 'PWD': '/builds/slave/test', 17:29:32 INFO - 'SHELL': '/bin/bash', 17:29:32 INFO - 'SHLVL': '1', 17:29:32 INFO - 'TERM': 'linux', 17:29:32 INFO - 'TMOUT': '86400', 17:29:32 INFO - 'USER': 'cltbld', 17:29:32 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:32 INFO - '_': '/tools/buildbot/bin/python'} 17:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:32 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 17:29:32 INFO - Cleaning up... 17:29:32 INFO - Return code: 0 17:29:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:29: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')]} 17:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29: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')]} 17:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29: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', '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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:29: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 psutil>=3.1.1 17:29:32 INFO - Using env: {'DISPLAY': ':0', 17:29:32 INFO - 'HOME': '/home/cltbld', 17:29:32 INFO - 'LANG': 'en_US.UTF-8', 17:29:32 INFO - 'LANGUAGE': 'en_US:en', 17:29:32 INFO - 'LOGNAME': 'cltbld', 17:29:32 INFO - 'MAIL': '/var/mail/cltbld', 17:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:32 INFO - 'MOZ_NO_REMOTE': '1', 17:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:32 INFO - 'NO_EM_RESTART': '1', 17:29:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:32 INFO - 'PWD': '/builds/slave/test', 17:29:32 INFO - 'SHELL': '/bin/bash', 17:29:32 INFO - 'SHLVL': '1', 17:29:32 INFO - 'TERM': 'linux', 17:29:32 INFO - 'TMOUT': '86400', 17:29:32 INFO - 'USER': 'cltbld', 17:29:32 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:32 INFO - '_': '/tools/buildbot/bin/python'} 17:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:33 INFO - Downloading/unpacking psutil>=3.1.1 17:29:33 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 17:29:33 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 17:29:33 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 17:29:33 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 17:29:33 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 17:29:33 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 17:29:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:29:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:29:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:29:34 INFO - Installing collected packages: psutil 17:29:34 INFO - Running setup.py install for psutil 17:29:35 INFO - building 'psutil._psutil_linux' extension 17:29:35 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 17:29:35 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 17:29:35 INFO - building 'psutil._psutil_posix' extension 17:29:35 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 17:29:35 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 17:29:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:29:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:29:35 INFO - Successfully installed psutil 17:29:35 INFO - Cleaning up... 17:29:35 INFO - Return code: 0 17:29:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:29: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')]} 17:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29: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')]} 17:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:29:35 INFO - Using env: {'DISPLAY': ':0', 17:29:35 INFO - 'HOME': '/home/cltbld', 17:29:35 INFO - 'LANG': 'en_US.UTF-8', 17:29:35 INFO - 'LANGUAGE': 'en_US:en', 17:29:35 INFO - 'LOGNAME': 'cltbld', 17:29:35 INFO - 'MAIL': '/var/mail/cltbld', 17:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:35 INFO - 'MOZ_NO_REMOTE': '1', 17:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:35 INFO - 'NO_EM_RESTART': '1', 17:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:35 INFO - 'PWD': '/builds/slave/test', 17:29:35 INFO - 'SHELL': '/bin/bash', 17:29:35 INFO - 'SHLVL': '1', 17:29:35 INFO - 'TERM': 'linux', 17:29:35 INFO - 'TMOUT': '86400', 17:29:35 INFO - 'USER': 'cltbld', 17:29:35 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:35 INFO - '_': '/tools/buildbot/bin/python'} 17:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:29: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 17:29: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 17:29: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 17:29: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 17:29: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 17:29: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 17:29:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:29:38 INFO - Installing collected packages: mozsystemmonitor 17:29:38 INFO - Running setup.py install for mozsystemmonitor 17:29:38 INFO - Successfully installed mozsystemmonitor 17:29:38 INFO - Cleaning up... 17:29:38 INFO - Return code: 0 17:29:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:38 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:38 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 17:29:38 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 17:29:38 INFO - Using env: {'DISPLAY': ':0', 17:29:38 INFO - 'HOME': '/home/cltbld', 17:29:38 INFO - 'LANG': 'en_US.UTF-8', 17:29:38 INFO - 'LANGUAGE': 'en_US:en', 17:29:38 INFO - 'LOGNAME': 'cltbld', 17:29:38 INFO - 'MAIL': '/var/mail/cltbld', 17:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:38 INFO - 'MOZ_NO_REMOTE': '1', 17:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:38 INFO - 'NO_EM_RESTART': '1', 17:29:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:38 INFO - 'PWD': '/builds/slave/test', 17:29:38 INFO - 'SHELL': '/bin/bash', 17:29:38 INFO - 'SHLVL': '1', 17:29:38 INFO - 'TERM': 'linux', 17:29:38 INFO - 'TMOUT': '86400', 17:29:38 INFO - 'USER': 'cltbld', 17:29:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:38 INFO - '_': '/tools/buildbot/bin/python'} 17:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:39 INFO - Downloading/unpacking jsonschema==2.5.1 17:29:39 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 17:29:39 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 17:29:39 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 17:29:39 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 17:29:39 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 17:29:39 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 17:29:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:29:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:29: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 17:29: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 17:29: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 17:29: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 17:29: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 17:29: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 17:29:41 INFO - Downloading functools32-3.2.3-2.tar.gz 17:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:29:41 INFO - warning: no files found matching '*.txt' 17:29:41 INFO - no previously-included directories found matching 'build' 17:29:41 INFO - no previously-included directories found matching 'dist' 17:29:41 INFO - no previously-included directories found matching '.git*' 17:29:41 INFO - Installing collected packages: jsonschema, functools32 17:29:41 INFO - Running setup.py install for functools32 17:29:41 INFO - warning: no files found matching '*.txt' 17:29:41 INFO - no previously-included directories found matching 'build' 17:29:41 INFO - no previously-included directories found matching 'dist' 17:29:41 INFO - no previously-included directories found matching '.git*' 17:29:41 INFO - Successfully installed jsonschema functools32 17:29:41 INFO - Cleaning up... 17:29:41 INFO - Return code: 0 17:29:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:41 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:41 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 17:29:41 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 17:29:41 INFO - Using env: {'DISPLAY': ':0', 17:29:41 INFO - 'HOME': '/home/cltbld', 17:29:41 INFO - 'LANG': 'en_US.UTF-8', 17:29:41 INFO - 'LANGUAGE': 'en_US:en', 17:29:41 INFO - 'LOGNAME': 'cltbld', 17:29:41 INFO - 'MAIL': '/var/mail/cltbld', 17:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:41 INFO - 'MOZ_NO_REMOTE': '1', 17:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:41 INFO - 'NO_EM_RESTART': '1', 17:29:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:41 INFO - 'PWD': '/builds/slave/test', 17:29:41 INFO - 'SHELL': '/bin/bash', 17:29:41 INFO - 'SHLVL': '1', 17:29:41 INFO - 'TERM': 'linux', 17:29:41 INFO - 'TMOUT': '86400', 17:29:41 INFO - 'USER': 'cltbld', 17:29:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:41 INFO - '_': '/tools/buildbot/bin/python'} 17:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:29:41 INFO - Cleaning up... 17:29:41 INFO - Return code: 0 17:29:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:41 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:41 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 17:29:41 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 17:29:41 INFO - Using env: {'DISPLAY': ':0', 17:29:41 INFO - 'HOME': '/home/cltbld', 17:29:41 INFO - 'LANG': 'en_US.UTF-8', 17:29:41 INFO - 'LANGUAGE': 'en_US:en', 17:29:41 INFO - 'LOGNAME': 'cltbld', 17:29:41 INFO - 'MAIL': '/var/mail/cltbld', 17:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:41 INFO - 'MOZ_NO_REMOTE': '1', 17:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:41 INFO - 'NO_EM_RESTART': '1', 17:29:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:41 INFO - 'PWD': '/builds/slave/test', 17:29:41 INFO - 'SHELL': '/bin/bash', 17:29:41 INFO - 'SHLVL': '1', 17:29:41 INFO - 'TERM': 'linux', 17:29:41 INFO - 'TMOUT': '86400', 17:29:41 INFO - 'USER': 'cltbld', 17:29:41 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:41 INFO - '_': '/tools/buildbot/bin/python'} 17:29:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:41 INFO - Downloading/unpacking blobuploader==1.2.4 17:29:41 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 17:29:41 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 17:29:41 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 17:29:41 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 17:29:41 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 17:29:41 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 17:29:43 INFO - Downloading blobuploader-1.2.4.tar.gz 17:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:29:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:29:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:29:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:29:44 INFO - Downloading docopt-0.6.1.tar.gz 17:29:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:29:44 INFO - Installing collected packages: blobuploader, requests, docopt 17:29:44 INFO - Running setup.py install for blobuploader 17:29:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:29:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:29:44 INFO - Running setup.py install for requests 17:29:44 INFO - Running setup.py install for docopt 17:29:45 INFO - Successfully installed blobuploader requests docopt 17:29:45 INFO - Cleaning up... 17:29:45 INFO - Return code: 0 17:29:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:45 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:45 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 17:29:45 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 17:29:45 INFO - Using env: {'DISPLAY': ':0', 17:29:45 INFO - 'HOME': '/home/cltbld', 17:29:45 INFO - 'LANG': 'en_US.UTF-8', 17:29:45 INFO - 'LANGUAGE': 'en_US:en', 17:29:45 INFO - 'LOGNAME': 'cltbld', 17:29:45 INFO - 'MAIL': '/var/mail/cltbld', 17:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:45 INFO - 'MOZ_NO_REMOTE': '1', 17:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:45 INFO - 'NO_EM_RESTART': '1', 17:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:45 INFO - 'PWD': '/builds/slave/test', 17:29:45 INFO - 'SHELL': '/bin/bash', 17:29:45 INFO - 'SHLVL': '1', 17:29:45 INFO - 'TERM': 'linux', 17:29:45 INFO - 'TMOUT': '86400', 17:29:45 INFO - 'USER': 'cltbld', 17:29:45 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:45 INFO - '_': '/tools/buildbot/bin/python'} 17:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:29:45 INFO - Running setup.py (path:/tmp/pip-Vf9Qe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:29:45 INFO - Running setup.py (path:/tmp/pip-YpkgCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:29:45 INFO - Running setup.py (path:/tmp/pip-LbD572-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:29:45 INFO - Running setup.py (path:/tmp/pip-EzeFTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:29:45 INFO - Running setup.py (path:/tmp/pip-FUlO05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:29:45 INFO - Running setup.py (path:/tmp/pip-V9l3b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:29:45 INFO - Running setup.py (path:/tmp/pip-pLdfcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:29:45 INFO - Running setup.py (path:/tmp/pip-Rklhys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:29:45 INFO - Running setup.py (path:/tmp/pip-wxINWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:29:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:29:45 INFO - Running setup.py (path:/tmp/pip-tP22Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:29:46 INFO - Running setup.py (path:/tmp/pip-8_in8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:29:46 INFO - Running setup.py (path:/tmp/pip-rLvzK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:29:46 INFO - Running setup.py (path:/tmp/pip-RmsM52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:29:46 INFO - Running setup.py (path:/tmp/pip-gu5ejR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:29:46 INFO - Running setup.py (path:/tmp/pip-0lnoFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:29:46 INFO - Running setup.py (path:/tmp/pip-NJcVzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:29:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:29:46 INFO - Running setup.py (path:/tmp/pip-jln8da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:29:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:29:46 INFO - Running setup.py install for manifestparser 17:29:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:29:46 INFO - Running setup.py install for mozcrash 17:29:46 INFO - Running setup.py install for mozdebug 17:29:46 INFO - Running setup.py install for mozdevice 17:29:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:29:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:29:46 INFO - Running setup.py install for mozfile 17:29:47 INFO - Running setup.py install for mozhttpd 17:29:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Running setup.py install for mozinfo 17:29:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Running setup.py install for mozInstall 17:29:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Running setup.py install for mozleak 17:29:47 INFO - Running setup.py install for mozlog 17:29:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Running setup.py install for moznetwork 17:29:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Running setup.py install for mozprocess 17:29:47 INFO - Running setup.py install for mozprofile 17:29:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:29:47 INFO - Running setup.py install for mozrunner 17:29:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:29:48 INFO - Running setup.py install for mozscreenshot 17:29:48 INFO - Running setup.py install for moztest 17:29:48 INFO - Running setup.py install for mozversion 17:29:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:29:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:29:48 INFO - Cleaning up... 17:29:48 INFO - Return code: 0 17:29:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:48 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:48 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 17:29:48 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 17:29:48 INFO - Using env: {'DISPLAY': ':0', 17:29:48 INFO - 'HOME': '/home/cltbld', 17:29:48 INFO - 'LANG': 'en_US.UTF-8', 17:29:48 INFO - 'LANGUAGE': 'en_US:en', 17:29:48 INFO - 'LOGNAME': 'cltbld', 17:29:48 INFO - 'MAIL': '/var/mail/cltbld', 17:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:48 INFO - 'MOZ_NO_REMOTE': '1', 17:29:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:48 INFO - 'NO_EM_RESTART': '1', 17:29:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:48 INFO - 'PWD': '/builds/slave/test', 17:29:48 INFO - 'SHELL': '/bin/bash', 17:29:48 INFO - 'SHLVL': '1', 17:29:48 INFO - 'TERM': 'linux', 17:29:48 INFO - 'TMOUT': '86400', 17:29:48 INFO - 'USER': 'cltbld', 17:29:48 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:48 INFO - '_': '/tools/buildbot/bin/python'} 17:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:29:48 INFO - Running setup.py (path:/tmp/pip-8f2QlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:29:48 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)) 17:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:29:48 INFO - Running setup.py (path:/tmp/pip-ERBXYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:29:48 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)) 17:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:29:48 INFO - Running setup.py (path:/tmp/pip-k7oLIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:29:48 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)) 17:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:29:48 INFO - Running setup.py (path:/tmp/pip-ynXih3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:29:48 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)) 17:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:29:48 INFO - Running setup.py (path:/tmp/pip-toc6Vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:29:48 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)) 17:29:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:29:48 INFO - Running setup.py (path:/tmp/pip-ZNMDLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:29:49 INFO - Running setup.py (path:/tmp/pip-SItNub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:29:49 INFO - Running setup.py (path:/tmp/pip-VXQ9Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:29:49 INFO - Running setup.py (path:/tmp/pip-kePxtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:29:49 INFO - Running setup.py (path:/tmp/pip-IAPKEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:29:49 INFO - Running setup.py (path:/tmp/pip-0grf9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:29:49 INFO - Running setup.py (path:/tmp/pip-y8lVb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:29:49 INFO - Running setup.py (path:/tmp/pip-R2P_Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:29:49 INFO - Running setup.py (path:/tmp/pip-DIsSgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:29:49 INFO - Running setup.py (path:/tmp/pip-drOc1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:29:49 INFO - Running setup.py (path:/tmp/pip-7EzuA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:29:49 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)) 17:29:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:29:49 INFO - Running setup.py (path:/tmp/pip-TCVdq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:29:49 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)) 17:29:49 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)) 17:29:49 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)) 17:29:49 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)) 17:29:49 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)) 17:29:49 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)) 17:29:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:29:49 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 17:29:49 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 17:29:49 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 17:29:49 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 17:29:49 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 17:29:49 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 17:29:51 INFO - Downloading blessings-1.6.tar.gz 17:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:29:51 INFO - Installing collected packages: blessings 17:29:51 INFO - Running setup.py install for blessings 17:29:51 INFO - Successfully installed blessings 17:29:51 INFO - Cleaning up... 17:29:51 INFO - Return code: 0 17:29:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:29:52 INFO - Reading from file tmpfile_stdout 17:29:52 INFO - Current package versions: 17:29:52 INFO - argparse == 1.2.1 17:29:52 INFO - blessings == 1.6 17:29:52 INFO - blobuploader == 1.2.4 17:29:52 INFO - docopt == 0.6.1 17:29:52 INFO - functools32 == 3.2.3-2 17:29:52 INFO - jsonschema == 2.5.1 17:29:52 INFO - manifestparser == 1.1 17:29:52 INFO - mozInstall == 1.12 17:29:52 INFO - mozcrash == 0.17 17:29:52 INFO - mozdebug == 0.1 17:29:52 INFO - mozdevice == 0.48 17:29:52 INFO - mozfile == 1.2 17:29:52 INFO - mozhttpd == 0.7 17:29:52 INFO - mozinfo == 0.9 17:29:52 INFO - mozleak == 0.1 17:29:52 INFO - mozlog == 3.3 17:29:52 INFO - moznetwork == 0.27 17:29:52 INFO - mozprocess == 0.23 17:29:52 INFO - mozprofile == 0.28 17:29:52 INFO - mozrunner == 6.12 17:29:52 INFO - mozscreenshot == 0.1 17:29:52 INFO - mozsystemmonitor == 0.3 17:29:52 INFO - moztest == 0.7 17:29:52 INFO - mozversion == 1.4 17:29:52 INFO - psutil == 3.1.1 17:29:52 INFO - requests == 1.2.3 17:29:52 INFO - wsgiref == 0.1.2 17:29:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:29:52 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')]} 17:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:52 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')]} 17:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:52 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:52 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 17:29:52 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 17:29:52 INFO - Using env: {'DISPLAY': ':0', 17:29:52 INFO - 'HOME': '/home/cltbld', 17:29:52 INFO - 'LANG': 'en_US.UTF-8', 17:29:52 INFO - 'LANGUAGE': 'en_US:en', 17:29:52 INFO - 'LOGNAME': 'cltbld', 17:29:52 INFO - 'MAIL': '/var/mail/cltbld', 17:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:52 INFO - 'MOZ_NO_REMOTE': '1', 17:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:52 INFO - 'NO_EM_RESTART': '1', 17:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:52 INFO - 'PWD': '/builds/slave/test', 17:29:52 INFO - 'SHELL': '/bin/bash', 17:29:52 INFO - 'SHLVL': '1', 17:29:52 INFO - 'TERM': 'linux', 17:29:52 INFO - 'TMOUT': '86400', 17:29:52 INFO - 'USER': 'cltbld', 17:29:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:52 INFO - '_': '/tools/buildbot/bin/python'} 17:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 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)) 17:29:52 INFO - Cleaning up... 17:29:52 INFO - Return code: 0 17:29:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:29:52 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')]} 17:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:52 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')]} 17:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:29:52 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 0x7fc475dd4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf6d230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', '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 17:29:52 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 17:29:52 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 17:29:52 INFO - Using env: {'DISPLAY': ':0', 17:29:52 INFO - 'HOME': '/home/cltbld', 17:29:52 INFO - 'LANG': 'en_US.UTF-8', 17:29:52 INFO - 'LANGUAGE': 'en_US:en', 17:29:52 INFO - 'LOGNAME': 'cltbld', 17:29:52 INFO - 'MAIL': '/var/mail/cltbld', 17:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:29:52 INFO - 'MOZ_NO_REMOTE': '1', 17:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:29:52 INFO - 'NO_EM_RESTART': '1', 17:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:29:52 INFO - 'PWD': '/builds/slave/test', 17:29:52 INFO - 'SHELL': '/bin/bash', 17:29:52 INFO - 'SHLVL': '1', 17:29:52 INFO - 'TERM': 'linux', 17:29:52 INFO - 'TMOUT': '86400', 17:29:52 INFO - 'USER': 'cltbld', 17:29:52 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:29:52 INFO - '_': '/tools/buildbot/bin/python'} 17:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:29:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:29:52 INFO - Cleaning up... 17:29:52 INFO - Return code: 0 17:29:52 INFO - Running post-action listener: _resource_record_post_action 17:29:52 INFO - Running post-action listener: _start_resource_monitoring 17:29:52 INFO - Starting resource monitoring. 17:29:52 INFO - [mozharness: 2016-08-31 00:29:52.626875Z] Finished create-virtualenv step (success) 17:29:52 INFO - [mozharness: 2016-08-31 00:29:52.627330Z] Running install step. 17:29:52 INFO - Running pre-action listener: _resource_record_pre_action 17:29:52 INFO - Running main action method: install 17:29:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:29:52 INFO - Reading from file tmpfile_stdout 17:29:52 INFO - Detecting whether we're running mozinstall >=1.0... 17:29:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:29:52 INFO - Reading from file tmpfile_stdout 17:29:52 INFO - Output received: 17:29:52 INFO - Usage: mozinstall [options] installer 17:29:52 INFO - Options: 17:29:52 INFO - -h, --help show this help message and exit 17:29:52 INFO - -d DEST, --destination=DEST 17:29:52 INFO - Directory to install application into. [default: 17:29:52 INFO - "/builds/slave/test"] 17:29:52 INFO - --app=APP Application being installed. [default: firefox] 17:29:52 INFO - mkdir: /builds/slave/test/build/application 17:29:52 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'] 17:29:52 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 17:30:09 INFO - Reading from file tmpfile_stdout 17:30:09 INFO - Output received: 17:30:09 INFO - /builds/slave/test/build/application/firefox/firefox 17:30:09 INFO - Running post-action listener: _resource_record_post_action 17:30:09 INFO - [mozharness: 2016-08-31 00:30:09.039489Z] Finished install step (success) 17:30:09 INFO - [mozharness: 2016-08-31 00:30:09.039780Z] Running run-tests step. 17:30:09 INFO - Running pre-action listener: _resource_record_pre_action 17:30:09 INFO - Running main action method: run_tests 17:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:30:09 INFO - Python 2.7.3 17:30:09 INFO - Return code: 0 17:30:09 INFO - grabbing minidump binary from tooltool 17:30:09 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')]} 17:30:09 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 0xf67090>, '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 0xf6a9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf6ae40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:30:09 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 17:30:09 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 17:30:09 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 17:30:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:30:09 INFO - Return code: 0 17:30:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:30:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:30:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:30:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:30:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:30:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 17:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 17:30:09 INFO - Using env: {'DISPLAY': ':0', 17:30:09 INFO - 'HOME': '/home/cltbld', 17:30:09 INFO - 'LANG': 'en_US.UTF-8', 17:30:09 INFO - 'LANGUAGE': 'en_US:en', 17:30:09 INFO - 'LOGNAME': 'cltbld', 17:30:09 INFO - 'MAIL': '/var/mail/cltbld', 17:30:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:30:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:30:09 INFO - 'MOZ_NO_REMOTE': '1', 17:30:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:30:09 INFO - 'NO_EM_RESTART': '1', 17:30:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:30:09 INFO - 'PWD': '/builds/slave/test', 17:30:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:30:09 INFO - 'SHELL': '/bin/bash', 17:30:09 INFO - 'SHLVL': '1', 17:30:09 INFO - 'TERM': 'linux', 17:30:09 INFO - 'TMOUT': '86400', 17:30:09 INFO - 'USER': 'cltbld', 17:30:09 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597', 17:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:30:09 INFO - '_': '/tools/buildbot/bin/python'} 17:30:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 17:30:09 INFO - mozversion application_buildid: 20160830165121 17:30:09 INFO - mozversion application_changeset: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 17:30:09 INFO - mozversion application_display_name: Nightly 17:30:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:30:09 INFO - mozversion application_name: Firefox 17:30:09 INFO - mozversion application_remotingname: firefox 17:30:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:30:09 INFO - mozversion application_vendor: Mozilla 17:30:09 INFO - mozversion application_version: 51.0a1 17:30:09 INFO - mozversion platform_buildid: 20160830165121 17:30:09 INFO - mozversion platform_changeset: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 17:30:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:30:09 INFO - mozversion platform_version: 51.0a1 17:30:09 INFO - using testdate: 1472603409 17:30:09 INFO - actual date: 1472603409 17:30:09 INFO - starting webserver on 'localhost:58651' 17:30:09 INFO - SUITE-START | Running 2 tests 17:30:09 INFO - TEST-START | dromaeo_css 17:30:09 INFO - operating with platform_type : linux_ 17:30:09 INFO - Initialising browser for dromaeo_css test... 17:30:09 INFO - TEST-INFO | started process 23760 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHV17Jj/profile http://localhost:58651/getInfo.html) 17:30:14 INFO - PROCESS | 23760 | __metrics Screen width/height:1600/1200 17:30:14 INFO - PROCESS | 23760 | colorDepth:24 17:30:14 INFO - PROCESS | 23760 | Browser inner width/height: 1024/697 17:30:14 INFO - PROCESS | 23760 | __metrics 17:30:14 INFO - PROCESS | 23760 | 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" 17:30:14 INFO - PROCESS | 23760 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:30:15 INFO - PROCESS | 23760 | console.error: PushService: 17:30:15 INFO - PROCESS | 23760 | stateChangeProcessEnqueue: Error transitioning state 17:30:15 INFO - PROCESS | 23760 | UnknownError 17:30:16 INFO - TEST-INFO | 23760: exit 0 17:30:16 INFO - Browser initialized. 17:30:16 INFO - Running cycle 1/1 for dromaeo_css test... 17:30:16 INFO - TEST-INFO | started process 23842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHV17Jj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:30:28 INFO - PROCESS | 23842 | RSS: Main: 185909248 17:30:28 INFO - PROCESS | 23842 | 17:31:19 INFO - PROCESS | 23842 | 1472603479367 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] 17:33:32 INFO - PROCESS | 23842 | Cycle 1(1): loaded http://localhost:58651/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58651/tests/dromaeo/cssquery-ext.html) 17:33:33 INFO - PROCESS | 23842 | RSS: Main: 277098496 17:33:33 INFO - PROCESS | 23842 | 17:36:36 INFO - PROCESS | 23842 | Cycle 1(1): loaded http://localhost:58651/tests/dromaeo/cssquery-ext.html (next: http://localhost:58651/tests/dromaeo/cssquery-jquery.html) 17:36:37 INFO - PROCESS | 23842 | RSS: Main: 274399232 17:36:37 INFO - PROCESS | 23842 | 17:39:39 INFO - PROCESS | 23842 | Cycle 1(1): loaded http://localhost:58651/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58651/tests/dromaeo/cssquery-mootools.html) 17:39:40 INFO - PROCESS | 23842 | RSS: Main: 219725824 17:39:40 INFO - PROCESS | 23842 | 17:42:42 INFO - PROCESS | 23842 | Cycle 1(1): loaded http://localhost:58651/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58651/tests/dromaeo/cssquery-prototype.html) 17:42:43 INFO - PROCESS | 23842 | RSS: Main: 219258880 17:42:43 INFO - PROCESS | 23842 | 17:45:46 INFO - PROCESS | 23842 | Cycle 1(1): loaded http://localhost:58651/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58651/tests/dromaeo/cssquery-yui.html) 17:45:46 INFO - PROCESS | 23842 | RSS: Main: 219815936 17:45:46 INFO - PROCESS | 23842 | 17:48:49 INFO - PROCESS | 23842 | Cycle 1(1): loaded http://localhost:58651/tests/dromaeo/cssquery-yui.html (next: http://localhost:58651/tests/dromaeo/cssquery-dojo.html) 17:48:50 INFO - PROCESS | 23842 | RSS: Main: 309186560 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | __start_tp_report 17:48:50 INFO - PROCESS | 23842 | _x_x_mozilla_page_load 17:48:50 INFO - PROCESS | 23842 | _x_x_mozilla_page_load_details 17:48:50 INFO - PROCESS | 23842 | |i|pagename|runs| 17:48:50 INFO - PROCESS | 23842 | |0;dojo.html;2565.256075738036;2569.1393383216623;2581.3804686875155;2593.0276146445085;2615.2546524240583;4406.559344065592;4464.040231350267;4544.659150563709;4596.747178905159;4605.447346318442;4523.801192683942;4537.591616754491;4537.750423726694;4538.727676339422;4547.931781023287;4196.517400498944;4257.723247988871;4305.138972205571;4305.698601097924;4319.157764235975;4201.075763332062;4211.241976444238;4246.7027308088445;4271.679624028195;4289.227938970984;4143.7306575072525;4391.209582275189;4393.406890069848;4396.406485124515;4408.25059739845;4264.402983582301;4267.594578515067;4271.829126834923;4290.806913688892;4346.174226896911;4106.1992911382295;4181.728187667792;4196.328587426027;4241.257779888507;4253.383259427396;4521.706089104197;4527.3435351873795;4541.682082254243;4552.408186935711;4610.05493873755;4909.852704418873;4910.705357678551;4915.729634870117;4942.20924652054;4996.25056241563;4825.469198388175;4907.607391408679;4956.1822299320565;4956.430010548797;4966.751662416901;4635.351050852917;4641.164590373698;4642.489326174118;4647.790849411785;4699.365585645894;5066;5066.645334826527;5069.822556210515;5071.264666623319;5121.206213036985;4672.486026537078;4770.141374552544;4801.567858892717;4822.42130944289;4901.705897646153;4875.293082503017;4888.437829649564;4896.094222568836;4905.852824415273;4969.875753106129;4860;4874.293227481995;4881.341018962466;4881.9023619528125;4934.284528743312;4752.049590081998;4793.209120495079;4801.975990120027;4871.147549178951;4881.438634557;4137.958620413758;4158.480189976253;4158.875233742932;4160.54234034256;4163.604457576586;3625.8549658013912;3639.0720366306423;3679.7608155469807;3714.981425092857;3736.23407201523;4244.830206791756;4248.978755106266;4250.489941207075;4290.099079193342;4310.202612516695;4858.7570621468785;4908.582770464551;4914.213725803854;4920.532549407801;4970.751462426864;4216.262154123016;4217.219814334358;4222.894427639334;4262.957370426318;4266.466691663542;4412.933805992912;4416.779161041935;4423.424954755926;4459.063596644676;4470.463544374631;4674.719516829001;4681.695689780221;4682.110399024311;4687.156311863897;4698.107359601665;4850.975745121252;4851.442084160367;4863.416390033219;4899.902001959869;4919.262110683426;4643.605293549951;4653.999390131138;4657.836975705834;4709.576138147583;4711.363965864564;4805.975970120127;4806.735629540409;4813.7352445615825;4816.277558366273;4865.756712164307;4796.6882152661365;4858.319835223142;4892.951070489249;4938.728369939688;4948.579370753526;4835.540623640749;4857.149998750276;4864.17309057454;4907.975460122677;4917.164082105979;4807.4231092267755;4833.05755377698;4842.491538388559;4854.732989685601;4919.0899683558655;4530.29780384041;4701.341812146371;4735.100330937111;4756.571908528249;4771.355866957916;4827.08285425768;4845;4846.36997190363;4880.560749532559;4909.803607855789;4708.858734237979;4729.574338309569;4746.121967436035;4747.928781068218;4807.639427043056 17:48:50 INFO - PROCESS | 23842 | |1;ext.html;556.8468671115444;563.3774678979727;567.6225310168738;581.2298704216911;584.2900875436344;27771.861140694295;36888.631113688854;37947.810260948725;38028.61971380285;41928.79035604825;39239.41140882882;39676.80161599188;39716.205675886464;39725.40411893824;39730.404043939365;7703.075630924283;7830.56931868747;7837.373010159174;7840.0591928968315;7840.13758486566;7706.6146692665425;7784.299413052824;7794.922050779476;7799.103103143126;7804.882926756075;7761.41789365797;7813.140554538996;7826.804329891741;7838.608069596526;7851.489653172554;7752.379809615274;7782.610869456533;7791.727289544839;7818.296353328199;7818.569978651172;7270.382017528516;7328.230535793718;7330.743423980189;7332.523385979895;7334.853302933911;36534.63465365339;36891.12643172482;36907.63092369069;36937.44593831095;36954.26091478182;2964;2971.777116716233;2995.7902946793733;3024.894128705507;3059.908202753899;28171.85914070437;28907.67561730386;28969.275768105756;29127;29583;3448.121889868481;3449.413599688059;3504.7721898076543;3514.876979305712;3556.590992035897;253140;255226;255424;255447;256165.7191714048;30864;51231.487685123415;51568.74215628898;51641.225381619304;51667.48332516702;31031;32792;32951;33013.17467063294;33020.66979330224;11389.829152562717;11453.942730286295;11469.483873225725;11495.942520287344;11502.827457588142;9455.385399948982;9536.523173841419;9555.665551705795;9556.187724043533;9556.378835375679;7581.924180758232;7622.237776222444;7642.923570764332;7682.76951691439;7688.538687678757;2976.2113040044405;3002.384511175204;3038.5897903782707;3045.193023848682;3067.5858759067687;779.3998621061864;867.7049803066988;888.5779254854021;892.6697122064877;898.8247291778172;25160.370990725012;26889.193324200307;26938.595921061198;27175.32061698434;27193.32016699559;694.4236283884364;852.7910661887877;887.6064578611647;918.2929144558786;936.5457752989795;13397.26315052662;14649.414023439156;15892.52322430329;15956.48413400725;16497.505074847773;376186;378380;378392;378436;379394;48560.27159592609;49212.26181607279;49256.75371623119;49284.26073608899;49704.25443618349;997.2221667099674;1087.4508373271447;1110.555777688947;1116.2911551165068;1124.9268797528134;25829.483410332065;27568.58647120335;28419.289517762223;28809.135725928256;29183.70816291895;41066;41754.58245417507;41786.58213417827;41796;41803;316179;316539;316764;320131;392286;36256.27487450183;36987.44518832274;37110.99001634853;37139.44290835586;37799.244015118995;41012.58987410088;41351.7932410336;41556.792216038724;42297;42451.3632295522;35185.648143518236;35472.29055418826;35622.28755424929;35885;36135.63864361323;34933.47599786056;35260.294794104484;35783;35911.1022224436;36201.0949726259;34197.31605367829;34474.65525344714;34610.82694586511;34648.82675586606;35673.1081722959;29413.85293073521;31586;31959.360812784078;32074.35851283008;32101.839490802395;24305.878470607535;24587;24654.753452465964;24945.981738342307;25041.74958250394 17:48:50 INFO - PROCESS | 23842 | |2;jquery.html;336.7911894625333;362.20857426523065;364.39146625136027;371.31863031337485;384.3216722484813;11606.59376921808;14845.183514906676;16779.077150756704;16958.40645577405;17607.559811004732;14648.80979042556;14852.777208341857;14969.625759356022;14983.850161498382;15029.549113526611;4905.754712264382;5079.7714102865275;5318.813841515548;5348.411674715778;5409.161579955094;3345.7156141727983;5328.973355133238;5413.837584872441;5440.401555828856;5449.100898351767;6600.306967768387;6905.792826215222;6926.757563485279;6936.271691472399;7042.929570704278;6652.301508341651;6786.660666966682;6882.483813713991;6886.24251332353;6891.448684105259;6345.746170153188;6640.037194606755;6682.465402767767;6688.264290927994;6688.29772873851;22587.774122258896;22965.196218132452;23067.423314417105;23151;23279.3016209514;1798.9370372037047;2136.722226110596;2260.3219034289646;2420.4311986683115;2615.777658898996;13242.404091815892;13926.860731392657;14140.151590904476;15021.399144034225;15121.09273443597;1807.2228941555127;1879.8872067675984;1893.0440127735544;1924.8171423714725;1949.4931317857458;58505.41494585085;73538;78481.60759196225;83527.5823620878;84000.57999709963;15000;15584.532464026097;16219.594510137105;16316.184190790413;16390.26243819031;27661;27745.861270693516;27776.16671499858;27809.582856257173;28016.859915700697;8153.79615509617;8163.714270000641;8170.836583268301;8213.95893020543;8578.78553036179;6465.1595292611655;6618.205815302098;6659.633720145439;6722.394984451423;7064.752733654297;8088.9039904859565;8185.386096042821;8305.626246818907;8312.501249925024;8369.916300837036;3181.42605677701;3269.836508174582;3283;3362.613299470592;3369.2419205678525;718.4073139659697;754.5661244784271;771.7530390275058;781.6326326626477;810.951342919415;15614.219289035504;15767.29047192879;16966.575835603962;17306.48080557585;17342.479725608253;806.5322113174345;808.2442915873677;820.7742870710603;826.8677011678103;838.1669955550201;8961.283097352196;9142.680006199884;9540.379875308083;9555.522223888778;9680.806383872283;373133;375008;391072;391243;391298;76929;78452.60773696096;78768;78842.6057869707;81813.18186818175;897.7486303834937;907.7912080221586;917.5412293853074;926.323783637948;928.7817362919709;14165.929170354082;14548.85451145475;14875.776863346842;14893.957422980287;14912.179830109448;38394.80802595969;39314.41028384634;39438.408423873094;39848.800755997196;41156.382654259614;48612.5138748608;48646.27030594615;48748.75625621849;49186.50813491819;51752;7033.964830175816;7289;7491;7572.356349710336;7587.962060189664;11234.494447749707;11656.650300490659;12018.098642602015;12048.216865903689;12158;9916.652917147865;9945.900540994788;10144.695659129942;10231.334963227366;10446.843297350393;9897.77300107499;10243.334183278062;10327.638532651321;10631;10655.520501577292;10125.240606954361;10286.382817031003;10320.174386048948;10661.8933810664;10750.301230419998;7258.782236532912;7304.269573042653;7420.6289685516585;7503.5497870127965;7604.391648667983;8187.795305117182;9838.754031148992;10254.23093267993;10563.68308950702;10572.312799667998 17:48:50 INFO - PROCESS | 23842 | |3;mootools.html;1226.760781647578;1340.5308142150245;1374.292239496659;1431.8711315981566;1652.8264532224116;6488.44848187904;6912.066870972417;7096.077509923703;7114.857702845947;7229.168645605757;7061.152661680592;7129.6791644376;7130.108736407949;7131.572105673663;7133.072700548922;2113.334299695595;2132.925347612834;2135.1779564867484;2138.8823614701187;2140.2081229944943;1999.8600097993146;2058.238451772846;2077.6883467479847;2082.302428686392;2089.8432617553744;2079.080555679725;2099.3701889433187;2109.8523103382768;2112.26070875194;2142.882141482218;2073.0567591745794;2078.1271865816393;2079.2826474866147;2081.2091405266056;2083.9270625528034;2008.7187793708893;2020.2626041494748;2047.406252186864;2053.240301088592;2053.291614393047;7102.857942841166;7206.963965180193;7300.853982920312;7305.780826575211;7330.670119844566;4195.077083041726;4311.245532031882;4332.891677708051;4339.175556644227;4345.152695224432;2693.9595906061427;2797.8880844766186;2853.5862299966598;2864.8137871038525;2867.985660071707;883.3992884838308;897.3075793111365;912.0286894457381;920.0569416348229;921.1939552891221;10025.54885030168;10052.547635356426;10052.648157314532;10164.847527287096;10189.541470633765;3609.7473176877893;3791.450239715226;3816.37029890065;3986.3223252047223;4003.8999025024023;7954.363650907913;8016.198380161937;8020;8025.799355016171;8042.235987581171;4128.876133715933;4129.91740165201;4132.235536425771;4132.8140233689555;4134.049168691218;3647.452882067711;3712.832922518493;3758.567764707094;3782.508273924372;3865.43951127085;3305.2812537480377;3313.8011719296437;3425.2806910549066;3434.6737059979264;3436.106612280825;998.256299057207;1016.3241444439365;1026.5329275179774;1028.4395004722444;1030.1501261459325;648.8604949935786;705;770.7803276066406;789.4118881433369;793.9166387529342;4617.607503362185;4800.447948485899;4822.059698358857;4863.8540843774745;5003.949170674126;390.79092685413167;804.4770898915751;840.3487297344485;880.8898887639045;915.8809354783835;3374.915627109342;3464.0993341730828;3492.9825350873084;3522.348365552381;3553.502509648651;8563.100874408223;8829.16122968317;9040.8643870342;9044.14080662336;9225.308101892386;9012.909870901338;9027.36808423404;9030.548472576345;9040.819183616422;9044.728658140266;6890.965545172242;6947.861042779217;7059.541129826647;7081.291870812981;7109.502334836615;4869.415670119467;4885.120678277802;4896.975515122402;4919.532644398776;5008.524190201925;8100.311473524816;8305.584720764058;8322.54226017575;8420.36847236472;8474.491530508189;8379.994400671958;8580.613872375883;8644.567771611268;8722.215000649972;8733.344999124964;5819;6335.4931605472375;6370.1718776558755;6526.314736952551;6612.867742645217;6980.057692211486;7102.467314951503;7108.2536333686285;7108.715651373887;7117.7152913882865;5992.250968628921;6001;6040.848978775566;6129.84675383119;6143.1706719592285;6048.36492168316;6093.055576385667;6108.908366374419;6108.908366374597;6123.142760013513;5854.121881717776;5868.823935281948;5898.9115163273445;5904.261967254093;5925.762969481172;4160.625543701081;4238.87283381499;4241.512226093915;4270.615644592001;4485.977570112129;4125.381192821101;4148.398482220001;4185.874423767292;4293.8711838644895;4448.5773851484055 17:48:50 INFO - PROCESS | 23842 | |4;prototype.html;295.06758642689107;296.31551116919917;299.9220202747285;301.5958615455291;305.5630448458706;4735.668503204777;4740.037476577139;4769.332293478911;4783.899703068292;4804.543568361;4735.668503204777;4737.313089602006;4755.334253204554;4757.8810529736775;4758.214894542396;3570.3751843427426;3589.9282014359696;3760.247950409915;3778.6032466590955;3811.6950643948417;3744.625537446247;3751.249750050001;3752.2495500899795;3767.5478942526797;3778.527684039495;3718.7582807117587;3730.5896351401325;3734.7012239020814;3742.419924911643;3752.0807402186365;3540.6105328413855;3567.090391950062;3710.5547334319785;3723.515942927429;3731;3219.5170724391296;3228.9354212915846;3230.6607806180245;3233.531137984979;3252.8536215870345;4539.251023581105;4580.931286030712;4609.67732258742;4615.8153673853;4622.468416132154;4062.7968601569805;4144.834206631731;4145.398917156996;4147.9170416591805;4151.211269858717;4879.048585525824;4887.364642596475;4896.485868983741;4898;4899.216125419951;3519.63043880394;3542.5040494330815;3549.165946002713;3551;3557.2707594942976;5096.464871188546;5113.795448182068;5125.513076257786;5128.538431541142;5135.974320128375;3353.714934230569;3526.806025668613;3542.574891013095;3553.200529880808;3627.766559369827;3564.198055437506;3599.5680518337967;3600.1179710970455;3606.6213047630145;3610.097475631092;4073.9592604073578;4080.2451546464;4085.346344584852;4092.304308267542;4095.7712686194022;4757.904841903142;4958.528939750718;4964.453910069889;4968.677035992649;4986.750662466862;1237.461704158694;1261.4323554400373;1263.109507797001;1264.3930913161735;1269.9746005079846;806.5563939833069;812.5530957973091;824.74432925792;826.7395770332326;827.1604321613567;3988.025927166071;4218.725782824106;4269.466316710411;4402.779861007001;4413.13943780966;5057.671251368649;5064.784451731632;5069.819977409676;5107.489251074937;5113.386393632788;4404.955950440519;4409.2724700424715;4412.580804823601;4423.800928958204;4424.955750442518;2627.1067836935536;2669.172556507489;2675.906343278015;2693.6094266331274;2700.770434513049;4802.015586804697;4810.518948105161;4846.466888642247;4866.2238283027145;4866.48901865299;5162.741862906914;5175.844724658191;5191.870203244949;5224.085784987612;5240.3973543042275;4655.604273636711;4736.502667219961;4822.517748225219;4829;4860.416749990024;5091.490850914879;5097.9490205098955;5097.992184611097;5099.286099945936;5130.793758683802;4896.36347274852;4943.283223932582;4965.255211718271;4997.800087996439;5021.221710634857;4597.770081510532;4801.399825021872;4828.637852161172;4836.637252206028;4855.5387238212315;3614.927701446009;3626.6192049835445;3628.9637103628625;3629.020164555608;3635.4910312556794;5080.110980578458;5097.286379906742;5100.846974590768;5121.641485096007;5121.9743901281745;4718.083990287984;4889.975550122226;4892.483330167659;4965.925511117264;4977.950220497749;4906.656534042726;4906.828261010847;4935.4570997190995;4944.975275123601;4948.505149485167;4880.90238195227;4880.90238195227;4882.462929077869;4890.633202509897;4910.558049775537;4688.765561721969;4727.125481785881;4981.227909674007;4984.40187177541;4986.700797951989;4692.694974826625;4844.8304309349005;4869.074875773734;4870.586000190024;4877.463598966314 17:48:50 INFO - PROCESS | 23842 | |5;yui.html;601.9699015049247;607.5443417436921;625.1279465146118;706.1032488739302;721.9639018049096;13060.869391306083;13924;13932.651683707913;13953.790693139586;13959.720805583882;13368.465261389558;13497.662558436044;13529.729405411887;13538;13543.848772854319;599.0624672387721;726;730.8172956760797;747.4095464582973;748.2666986353377;746.9775906722807;748.2816496163689;748.9400847932179;749.2806905370851;749.3181205103357;793.2266040610388;794.0907660728462;794.2216627704853;794.2573693596477;794.566961006253;637.687533108778;775.3370867907914;791.5290402210676;794.3764145146033;798.0064819299951;560.4255637971072;565.2482198675751;565.8755073883739;566.0546886699223;566.0801198053164;12624.495020199181;12737.681557961125;12740.80888786669;12773.552925647558;12791.360431978364;11258.43707814606;11550.653480395602;11622.76754464906;11720.882791172064;12255.57105501312;11732.589359372381;11845.94077029618;11946.163768536206;11959.40202989847;12102.939485302606;3193.984030079858;3288.62180849203;3429.9828500857584;3445.552078229839;4055.3511438170044;52015.73992130015;54528.727356363364;60908.69545652287;61106.38893611007;62159.68920155415;9662.3719458235;9669.492750372445;9788.461634610165;9817.214622830157;9907.25695572835;17414.825851741574;17586.736198957027;17625.118744063002;17684.292628294723;17861.553461163574;6646.966765166143;6672.2994085621285;6718.49611279156;6844;6940.13248343957;3542.9645703542633;3699.6300369962787;3711.3690672585185;3753.305638456894;3779.622037796253;2822.971770282312;2956.645202575705;2957.0685234151174;2960.807547509405;2964.584958105867;464.61901240982746;476.3045952908792;481.40546425164894;570.1390899741338;599.0594766217084;399.8720409468942;447.8678789757029;451.2193904545155;474.890775121717;478.9712617242977;6662.367075127855;7105.944443889177;7261.818454538678;7455.701771929171;7745.186755390603;448.34765416319175;496.13919849061847;501.2981825444407;507.0821812519278;513.3019094032113;3427.708644765173;3459.377312083849;3513.4202856528386;3587.157018100744;3673.485712000322;51168.744156279725;51797.74101129545;53030.46969530333;53150.468495314555;101556.76648502784;73611;74857.62571187218;75016;75396.62301688566;75544.62227688826;68136.6593167031;69677.30385355609;70196.29803701898;71412.2858771416;71956;7152.642367881481;7163.928360716326;7307.7807665770115;7427.962860185665;7437.925620743939;34295.31409371849;41960;42349;42740.572594274905;43042;56715.43284567101;58424.70787646034;60591.15270778239;62381.68809155925;66745;10085.142762864945;10201.84697229557;10258.58965641366;10280.485975701335;10330.638427654996;11000.284981476178;11003.889961100607;11034.779304414027;11241.88758112441;11842.348670823187;10400.479976001321;10790.67627971162;10905.618303359663;10933.726656833325;10988.285761425483;10493.842592360968;11047.834282485608;11244;12257.509699611915;12310.50757969707;10614.522346494581;10731.517081731185;11310.547578096784;11311.717207069889;11429.942850285695;8754.343424243334;8962.865557016781;9056.411333263317;9147.222486088756;9548.045195480674;8996.595153218252;9422.05342195353;9476.945144485031;9570.712878613653;9585.808283834423 17:48:50 INFO - PROCESS | 23842 | __end_tp_report 17:48:50 INFO - PROCESS | 23842 | __start_cc_report 17:48:50 INFO - PROCESS | 23842 | _x_x_mozilla_cycle_collect,914 17:48:50 INFO - PROCESS | 23842 | __end_cc_report 17:48:50 INFO - PROCESS | 23842 | __startTimestamp1472604530101__endTimestamp 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | ------- Summary: start ------- 17:48:50 INFO - PROCESS | 23842 | Number of tests: 6 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | [#0] dojo.html Cycles:165 Average:4552.08 Median:4687.16 stddev:468.47 (10.0%) stddev-sans-first:443.22 17:48:50 INFO - PROCESS | 23842 | Values: 2565.3 2569.1 2581.4 2593.0 2615.3 4406.6 4464.0 4544.7 4596.7 4605.4 4523.8 4537.6 4537.8 4538.7 4547.9 4196.5 4257.7 4305.1 4305.7 4319.2 4201.1 4211.2 4246.7 4271.7 4289.2 4143.7 4391.2 4393.4 4396.4 4408.3 4264.4 4267.6 4271.8 4290.8 4346.2 4106.2 4181.7 4196.3 4241.3 4253.4 4521.7 4527.3 4541.7 4552.4 4610.1 4909.9 4910.7 4915.7 4942.2 4996.3 4825.5 4907.6 4956.2 4956.4 4966.8 4635.4 4641.2 4642.5 4647.8 4699.4 5066.0 5066.6 5069.8 5071.3 5121.2 4672.5 4770.1 4801.6 4822.4 4901.7 4875.3 4888.4 4896.1 4905.9 4969.9 4860.0 4874.3 4881.3 4881.9 4934.3 4752.0 4793.2 4802.0 4871.1 4881.4 4138.0 4158.5 4158.9 4160.5 4163.6 3625.9 3639.1 3679.8 3715.0 3736.2 4244.8 4249.0 4250.5 4290.1 4310.2 4858.8 4908.6 4914.2 4920.5 4970.8 4216.3 4217.2 4222.9 4263.0 4266.5 4412.9 4416.8 4423.4 4459.1 4470.5 4674.7 4681.7 4682.1 4687.2 4698.1 4851.0 4851.4 4863.4 4899.9 4919.3 4643.6 4654.0 4657.8 4709.6 4711.4 4806.0 4806.7 4813.7 4816.3 4865.8 4796.7 4858.3 4893.0 4938.7 4948.6 4835.5 4857.1 4864.2 4908.0 4917.2 4807.4 4833.1 4842.5 4854.7 4919.1 4530.3 4701.3 4735.1 4756.6 4771.4 4827.1 4845.0 4846.4 4880.6 4909.8 4708.9 4729.6 4746.1 4747.9 4807.6 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | [#1] ext.html Cycles:180 Average:46367.95 Median:36945.85 stddev:86117.54 (233.1%) stddev-sans-first:86290.43 17:48:50 INFO - PROCESS | 23842 | Values: 556.8 563.4 567.6 581.2 584.3 27771.9 36888.6 37947.8 38028.6 41928.8 39239.4 39676.8 39716.2 39725.4 39730.4 7703.1 7830.6 7837.4 7840.1 7840.1 7706.6 7784.3 7794.9 7799.1 7804.9 7761.4 7813.1 7826.8 7838.6 7851.5 7752.4 7782.6 7791.7 7818.3 7818.6 7270.4 7328.2 7330.7 7332.5 7334.9 36534.6 36891.1 36907.6 36937.4 36954.3 2964.0 2971.8 2995.8 3024.9 3059.9 28171.9 28907.7 28969.3 29127.0 29583.0 3448.1 3449.4 3504.8 3514.9 3556.6 253140.0 255226.0 255424.0 255447.0 256165.7 30864.0 51231.5 51568.7 51641.2 51667.5 31031.0 32792.0 32951.0 33013.2 33020.7 11389.8 11453.9 11469.5 11495.9 11502.8 9455.4 9536.5 9555.7 9556.2 9556.4 7581.9 7622.2 7642.9 7682.8 7688.5 2976.2 3002.4 3038.6 3045.2 3067.6 779.4 867.7 888.6 892.7 898.8 25160.4 26889.2 26938.6 27175.3 27193.3 694.4 852.8 887.6 918.3 936.5 13397.3 14649.4 15892.5 15956.5 16497.5 376186.0 378380.0 378392.0 378436.0 379394.0 48560.3 49212.3 49256.8 49284.3 49704.3 997.2 1087.5 1110.6 1116.3 1124.9 25829.5 27568.6 28419.3 28809.1 29183.7 41066.0 41754.6 41786.6 41796.0 41803.0 316179.0 316539.0 316764.0 320131.0 392286.0 36256.3 36987.4 37111.0 37139.4 37799.2 41012.6 41351.8 41556.8 42297.0 42451.4 35185.6 35472.3 35622.3 35885.0 36135.6 34933.5 35260.3 35783.0 35911.1 36201.1 34197.3 34474.7 34610.8 34648.8 35673.1 29413.9 31586.0 31959.4 32074.4 32101.8 24305.9 24587.0 24654.8 24946.0 25041.7 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | [#2] jquery.html Cycles:180 Average:25320.17 Median:44884.45 stddev:63562.71 (141.6%) stddev-sans-first:63713.34 17:48:50 INFO - PROCESS | 23842 | Values: 336.8 362.2 364.4 371.3 384.3 11606.6 14845.2 16779.1 16958.4 17607.6 14648.8 14852.8 14969.6 14983.9 15029.5 4905.8 5079.8 5318.8 5348.4 5409.2 3345.7 5329.0 5413.8 5440.4 5449.1 6600.3 6905.8 6926.8 6936.3 7042.9 6652.3 6786.7 6882.5 6886.2 6891.4 6345.7 6640.0 6682.5 6688.3 6688.3 22587.8 22965.2 23067.4 23151.0 23279.3 1798.9 2136.7 2260.3 2420.4 2615.8 13242.4 13926.9 14140.2 15021.4 15121.1 1807.2 1879.9 1893.0 1924.8 1949.5 58505.4 73538.0 78481.6 83527.6 84000.6 15000.0 15584.5 16219.6 16316.2 16390.3 27661.0 27745.9 27776.2 27809.6 28016.9 8153.8 8163.7 8170.8 8214.0 8578.8 6465.2 6618.2 6659.6 6722.4 7064.8 8088.9 8185.4 8305.6 8312.5 8369.9 3181.4 3269.8 3283.0 3362.6 3369.2 718.4 754.6 771.8 781.6 811.0 15614.2 15767.3 16966.6 17306.5 17342.5 806.5 808.2 820.8 826.9 838.2 8961.3 9142.7 9540.4 9555.5 9680.8 373133.0 375008.0 391072.0 391243.0 391298.0 76929.0 78452.6 78768.0 78842.6 81813.2 897.7 907.8 917.5 926.3 928.8 14165.9 14548.9 14875.8 14894.0 14912.2 38394.8 39314.4 39438.4 39848.8 41156.4 48612.5 48646.3 48748.8 49186.5 51752.0 7034.0 7289.0 7491.0 7572.4 7588.0 11234.5 11656.7 12018.1 12048.2 12158.0 9916.7 9945.9 10144.7 10231.3 10446.8 9897.8 10243.3 10327.6 10631.0 10655.5 10125.2 10286.4 10320.2 10661.9 10750.3 7258.8 7304.3 7420.6 7503.5 7604.4 8187.8 9838.8 10254.2 10563.7 10572.3 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | [#3] mootools.html Cycles:180 Average:4779.48 Median:4891.05 stddev:2670.84 (54.6%) stddev-sans-first:2664.99 17:48:50 INFO - PROCESS | 23842 | Values: 1226.8 1340.5 1374.3 1431.9 1652.8 6488.4 6912.1 7096.1 7114.9 7229.2 7061.2 7129.7 7130.1 7131.6 7133.1 2113.3 2132.9 2135.2 2138.9 2140.2 1999.9 2058.2 2077.7 2082.3 2089.8 2079.1 2099.4 2109.9 2112.3 2142.9 2073.1 2078.1 2079.3 2081.2 2083.9 2008.7 2020.3 2047.4 2053.2 2053.3 7102.9 7207.0 7300.9 7305.8 7330.7 4195.1 4311.2 4332.9 4339.2 4345.2 2694.0 2797.9 2853.6 2864.8 2868.0 883.4 897.3 912.0 920.1 921.2 10025.5 10052.5 10052.6 10164.8 10189.5 3609.7 3791.5 3816.4 3986.3 4003.9 7954.4 8016.2 8020.0 8025.8 8042.2 4128.9 4129.9 4132.2 4132.8 4134.0 3647.5 3712.8 3758.6 3782.5 3865.4 3305.3 3313.8 3425.3 3434.7 3436.1 998.3 1016.3 1026.5 1028.4 1030.2 648.9 705.0 770.8 789.4 793.9 4617.6 4800.4 4822.1 4863.9 5003.9 390.8 804.5 840.3 880.9 915.9 3374.9 3464.1 3493.0 3522.3 3553.5 8563.1 8829.2 9040.9 9044.1 9225.3 9012.9 9027.4 9030.5 9040.8 9044.7 6891.0 6947.9 7059.5 7081.3 7109.5 4869.4 4885.1 4897.0 4919.5 5008.5 8100.3 8305.6 8322.5 8420.4 8474.5 8380.0 8580.6 8644.6 8722.2 8733.3 5819.0 6335.5 6370.2 6526.3 6612.9 6980.1 7102.5 7108.3 7108.7 7117.7 5992.3 6001.0 6040.8 6129.8 6143.2 6048.4 6093.1 6108.9 6108.9 6123.1 5854.1 5868.8 5898.9 5904.3 5925.8 4160.6 4238.9 4241.5 4270.6 4486.0 4125.4 4148.4 4185.9 4293.9 4448.6 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | [#4] prototype.html Cycles:180 Average:4100.85 Median:4731.40 stddev:1193.16 (25.2%) stddev-sans-first:1161.81 17:48:50 INFO - PROCESS | 23842 | Values: 295.1 296.3 299.9 301.6 305.6 4735.7 4740.0 4769.3 4783.9 4804.5 4735.7 4737.3 4755.3 4757.9 4758.2 3570.4 3589.9 3760.2 3778.6 3811.7 3744.6 3751.2 3752.2 3767.5 3778.5 3718.8 3730.6 3734.7 3742.4 3752.1 3540.6 3567.1 3710.6 3723.5 3731.0 3219.5 3228.9 3230.7 3233.5 3252.9 4539.3 4580.9 4609.7 4615.8 4622.5 4062.8 4144.8 4145.4 4147.9 4151.2 4879.0 4887.4 4896.5 4898.0 4899.2 3519.6 3542.5 3549.2 3551.0 3557.3 5096.5 5113.8 5125.5 5128.5 5136.0 3353.7 3526.8 3542.6 3553.2 3627.8 3564.2 3599.6 3600.1 3606.6 3610.1 4074.0 4080.2 4085.3 4092.3 4095.8 4757.9 4958.5 4964.5 4968.7 4986.8 1237.5 1261.4 1263.1 1264.4 1270.0 806.6 812.6 824.7 826.7 827.2 3988.0 4218.7 4269.5 4402.8 4413.1 5057.7 5064.8 5069.8 5107.5 5113.4 4405.0 4409.3 4412.6 4423.8 4425.0 2627.1 2669.2 2675.9 2693.6 2700.8 4802.0 4810.5 4846.5 4866.2 4866.5 5162.7 5175.8 5191.9 5224.1 5240.4 4655.6 4736.5 4822.5 4829.0 4860.4 5091.5 5097.9 5098.0 5099.3 5130.8 4896.4 4943.3 4965.3 4997.8 5021.2 4597.8 4801.4 4828.6 4836.6 4855.5 3614.9 3626.6 3629.0 3629.0 3635.5 5080.1 5097.3 5100.8 5121.6 5122.0 4718.1 4890.0 4892.5 4965.9 4978.0 4906.7 4906.8 4935.5 4945.0 4948.5 4880.9 4880.9 4882.5 4890.6 4910.6 4688.8 4727.1 4981.2 4984.4 4986.7 4692.7 4844.8 4869.1 4870.6 4877.5 17:48:50 INFO - PROCESS | 23842 | 17:48:50 INFO - PROCESS | 23842 | [#5] yui.html Cycles:180 Average:15895.88 Median:498.72 stddev:21602.55 (4331.6%) stddev-sans-first:21632.63 17:48:50 INFO - PROCESS | 23842 | Values: 602.0 607.5 625.1 706.1 722.0 13060.9 13924.0 13932.7 13953.8 13959.7 13368.5 13497.7 13529.7 13538.0 13543.8 599.1 726.0 730.8 747.4 748.3 747.0 748.3 748.9 749.3 749.3 793.2 794.1 794.2 794.3 794.6 637.7 775.3 791.5 794.4 798.0 560.4 565.2 565.9 566.1 566.1 12624.5 12737.7 12740.8 12773.6 12791.4 11258.4 11550.7 11622.8 11720.9 12255.6 11732.6 11845.9 11946.2 11959.4 12102.9 3194.0 3288.6 3430.0 3445.6 4055.4 52015.7 54528.7 60908.7 61106.4 62159.7 9662.4 9669.5 9788.5 9817.2 9907.3 17414.8 17586.7 17625.1 17684.3 17861.6 6647.0 6672.3 6718.5 6844.0 6940.1 3543.0 3699.6 3711.4 3753.3 3779.6 2823.0 2956.6 2957.1 2960.8 2964.6 464.6 476.3 481.4 570.1 599.1 399.9 447.9 451.2 474.9 479.0 6662.4 7105.9 7261.8 7455.7 7745.2 448.3 496.1 501.3 507.1 513.3 3427.7 3459.4 3513.4 3587.2 3673.5 51168.7 51797.7 53030.5 53150.5 101556.8 73611.0 74857.6 75016.0 75396.6 75544.6 68136.7 69677.3 70196.3 71412.3 71956.0 7152.6 7163.9 7307.8 7428.0 7437.9 34295.3 41960.0 42349.0 42740.6 43042.0 56715.4 58424.7 60591.2 62381.7 66745.0 10085.1 10201.8 10258.6 10280.5 10330.6 11000.3 11003.9 11034.8 11241.9 11842.3 10400.5 10790.7 10905.6 10933.7 10988.3 10493.8 11047.8 11244.0 12257.5 12310.5 10614.5 10731.5 11310.5 11311.7 11429.9 8754.3 8962.9 9056.4 9147.2 9548.0 8996.6 9422.1 9476.9 9570.7 9585.8 17:48:50 INFO - PROCESS | 23842 | -------- Summary: end -------- 17:48:50 INFO - PROCESS | 23842 | 17:48:51 INFO - TEST-INFO | 23842: exit 0 17:48:51 INFO - TEST-OK | dromaeo_css | took 1121789ms 17:48:51 INFO - TEST-START | kraken 17:48:51 INFO - operating with platform_type : linux_ 17:48:51 INFO - Initialising browser for kraken test... 17:48:51 INFO - TEST-INFO | started process 24149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmAYWVs/profile http://localhost:58651/getInfo.html) 17:48:56 INFO - PROCESS | 24149 | __metrics Screen width/height:1600/1200 17:48:56 INFO - PROCESS | 24149 | colorDepth:24 17:48:56 INFO - PROCESS | 24149 | Browser inner width/height: 1024/697 17:48:56 INFO - PROCESS | 24149 | __metrics 17:48:56 INFO - PROCESS | 24149 | 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" 17:48:56 INFO - PROCESS | 24149 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:48:57 INFO - PROCESS | 24149 | console.error: PushService: 17:48:57 INFO - PROCESS | 24149 | stateChangeProcessEnqueue: Error transitioning state 17:48:57 INFO - PROCESS | 24149 | UnknownError 17:48:58 INFO - TEST-INFO | 24149: exit 0 17:48:58 INFO - Browser initialized. 17:48:58 INFO - Running cycle 1/1 for kraken test... 17:48:58 INFO - TEST-INFO | started process 24231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmAYWVs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:49:10 INFO - PROCESS | 24231 | RSS: Main: 181784576 17:49:10 INFO - PROCESS | 24231 | 17:49:13 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58651/tests/kraken/driver.html?testName=audio-beat-detection) 17:49:14 INFO - PROCESS | 24231 | RSS: Main: 234864640 17:49:14 INFO - PROCESS | 24231 | 17:49:16 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58651/tests/kraken/driver.html?testName=audio-dft) 17:49:17 INFO - PROCESS | 24231 | RSS: Main: 225837056 17:49:17 INFO - PROCESS | 24231 | 17:49:20 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58651/tests/kraken/driver.html?testName=audio-fft) 17:49:21 INFO - PROCESS | 24231 | RSS: Main: 305565696 17:49:21 INFO - PROCESS | 24231 | 17:49:23 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58651/tests/kraken/driver.html?testName=audio-oscillator) 17:49:24 INFO - PROCESS | 24231 | RSS: Main: 213622784 17:49:24 INFO - PROCESS | 24231 | 17:49:26 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58651/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:49:26 INFO - PROCESS | 24231 | RSS: Main: 216027136 17:49:26 INFO - PROCESS | 24231 | 17:49:30 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58651/tests/kraken/driver.html?testName=imaging-darkroom) 17:49:30 INFO - PROCESS | 24231 | RSS: Main: 255295488 17:49:30 INFO - PROCESS | 24231 | 17:49:34 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58651/tests/kraken/driver.html?testName=imaging-desaturate) 17:49:34 INFO - PROCESS | 24231 | RSS: Main: 249384960 17:49:34 INFO - PROCESS | 24231 | 17:49:37 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58651/tests/kraken/driver.html?testName=json-parse-financial) 17:49:38 INFO - PROCESS | 24231 | RSS: Main: 252538880 17:49:38 INFO - PROCESS | 24231 | 17:49:40 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58651/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:49:40 INFO - PROCESS | 24231 | RSS: Main: 215945216 17:49:40 INFO - PROCESS | 24231 | 17:49:42 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:49:43 INFO - PROCESS | 24231 | RSS: Main: 223780864 17:49:43 INFO - PROCESS | 24231 | 17:49:45 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:49:46 INFO - PROCESS | 24231 | RSS: Main: 222650368 17:49:46 INFO - PROCESS | 24231 | 17:49:48 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:49:49 INFO - PROCESS | 24231 | RSS: Main: 223531008 17:49:49 INFO - PROCESS | 24231 | 17:49:52 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:49:53 INFO - PROCESS | 24231 | RSS: Main: 221556736 17:49:53 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | Cycle 1(1): loaded http://localhost:58651/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58651/tests/kraken/driver.html?testName=ai-astar) 17:49:55 INFO - PROCESS | 24231 | RSS: Main: 273141760 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | __start_tp_report 17:49:55 INFO - PROCESS | 24231 | _x_x_mozilla_page_load 17:49:55 INFO - PROCESS | 24231 | _x_x_mozilla_page_load_details 17:49:55 INFO - PROCESS | 24231 | |i|pagename|runs| 17:49:55 INFO - PROCESS | 24231 | |0;ai-astar;114;117;133;114;116;116;113;117;116;116 17:49:55 INFO - PROCESS | 24231 | |1;audio-beat-detection;155;123;149;191;128;154;147;125;190;123 17:49:55 INFO - PROCESS | 24231 | |2;audio-dft;164;161;160;163;161;162;161;162;160;168 17:49:55 INFO - PROCESS | 24231 | |3;audio-fft;76;77;76;173;77;78;75;76;154;76 17:49:55 INFO - PROCESS | 24231 | |4;audio-oscillator;85;83;148;84;82;82;131;83;84;142 17:49:55 INFO - PROCESS | 24231 | |5;imaging-gaussian-blur;116;117;117;118;117;117;117;116;117;116 17:49:55 INFO - PROCESS | 24231 | |6;imaging-darkroom;147;147;151;147;147;147;148;146;146;146 17:49:55 INFO - PROCESS | 24231 | |7;imaging-desaturate;94;95;95;95;95;96;96;94;95;94 17:49:55 INFO - PROCESS | 24231 | |8;json-parse-financial;66;67;69;64;66;68;67;67;68;66 17:49:55 INFO - PROCESS | 24231 | |9;json-stringify-tinderbox;65;66;66;65;66;67;66;65;66;66 17:49:55 INFO - PROCESS | 24231 | |10;stanford-crypto-aes;83;91;82;83;83;91;85;82;82;82 17:49:55 INFO - PROCESS | 24231 | |11;stanford-crypto-ccm;152;133;128;142;134;151;133;131;174;151 17:49:55 INFO - PROCESS | 24231 | |12;stanford-crypto-pbkdf2;176;176;173;174;174;177;173;176;175;173 17:49:55 INFO - PROCESS | 24231 | |13;stanford-crypto-sha256-iterative;68;70;70;69;71;70;73;73;68;72 17:49:55 INFO - PROCESS | 24231 | __end_tp_report 17:49:55 INFO - PROCESS | 24231 | __start_cc_report 17:49:55 INFO - PROCESS | 24231 | _x_x_mozilla_cycle_collect,2029 17:49:55 INFO - PROCESS | 24231 | __end_cc_report 17:49:55 INFO - PROCESS | 24231 | __startTimestamp1472604595257__endTimestamp 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | ------- Summary: start ------- 17:49:55 INFO - PROCESS | 24231 | Number of tests: 14 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#0] ai-astar Cycles:10 Average:117.20 Median:116.00 stddev:5.71 (4.9%) stddev-sans-first:5.94 17:49:55 INFO - PROCESS | 24231 | Values: 114.0 117.0 133.0 114.0 116.0 116.0 113.0 117.0 116.0 116.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:151.50 stddev:25.55 (16.9%) stddev-sans-first:26.99 17:49:55 INFO - PROCESS | 24231 | Values: 155.0 123.0 149.0 191.0 128.0 154.0 147.0 125.0 190.0 123.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:2.39 (1.5%) stddev-sans-first:2.45 17:49:55 INFO - PROCESS | 24231 | Values: 164.0 161.0 160.0 163.0 161.0 162.0 161.0 162.0 160.0 168.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#3] audio-fft Cycles:10 Average:93.80 Median:76.00 stddev:37.02 (48.7%) stddev-sans-first:38.70 17:49:55 INFO - PROCESS | 24231 | Values: 76.0 77.0 76.0 173.0 77.0 78.0 75.0 76.0 154.0 76.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:83.00 stddev:27.87 (33.6%) stddev-sans-first:29.00 17:49:55 INFO - PROCESS | 24231 | Values: 85.0 83.0 148.0 84.0 82.0 82.0 131.0 83.0 84.0 142.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 17:49:55 INFO - PROCESS | 24231 | Values: 116.0 117.0 117.0 118.0 117.0 117.0 117.0 116.0 117.0 116.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.48 (1.0%) stddev-sans-first:1.56 17:49:55 INFO - PROCESS | 24231 | Values: 147.0 147.0 151.0 147.0 147.0 147.0 148.0 146.0 146.0 146.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 17:49:55 INFO - PROCESS | 24231 | Values: 94.0 95.0 95.0 95.0 95.0 96.0 96.0 94.0 95.0 94.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:67.00 stddev:1.40 (2.1%) stddev-sans-first:1.45 17:49:55 INFO - PROCESS | 24231 | Values: 66.0 67.0 69.0 64.0 66.0 68.0 67.0 67.0 68.0 66.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 17:49:55 INFO - PROCESS | 24231 | Values: 65.0 66.0 66.0 65.0 66.0 67.0 66.0 65.0 66.0 66.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.00 stddev:3.60 (4.3%) stddev-sans-first:3.78 17:49:55 INFO - PROCESS | 24231 | Values: 83.0 91.0 82.0 83.0 83.0 91.0 85.0 82.0 82.0 82.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:146.50 stddev:14.22 (9.7%) stddev-sans-first:14.70 17:49:55 INFO - PROCESS | 24231 | Values: 152.0 133.0 128.0 142.0 134.0 151.0 133.0 131.0 174.0 151.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.50 stddev:1.49 (0.9%) stddev-sans-first:1.51 17:49:55 INFO - PROCESS | 24231 | Values: 176.0 176.0 173.0 174.0 174.0 177.0 173.0 176.0 175.0 173.0 17:49:55 INFO - PROCESS | 24231 | 17:49:55 INFO - PROCESS | 24231 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:70.50 stddev:1.84 (2.6%) stddev-sans-first:1.73 17:49:55 INFO - PROCESS | 24231 | Values: 68.0 70.0 70.0 69.0 71.0 70.0 73.0 73.0 68.0 72.0 17:49:55 INFO - PROCESS | 24231 | -------- Summary: end -------- 17:49:55 INFO - PROCESS | 24231 | 17:49:56 INFO - TEST-INFO | 24231: exit 0 17:49:56 INFO - TEST-OK | kraken | took 65457ms 17:49:56 INFO - SUITE-END | took 1187s 17:49:56 INFO - Completed test suite (00:19:47) 17:49:56 INFO - javascript benchmark 17:49:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2565.256075738036, 2569.1393383216623, 2581.3804686875155, 2593.0276146445085, 2615.2546524240583, 4406.559344065592, 4464.040231350267, 4544.659150563709, 4596.747178905159, 4605.447346318442, 4523.801192683942, 4537.591616754491, 4537.750423726694, 4538.727676339422, 4547.931781023287, 4196.517400498944, 4257.723247988871, 4305.138972205571, 4305.698601097924, 4319.157764235975, 4201.075763332062, 4211.241976444238, 4246.7027308088445, 4271.679624028195, 4289.227938970984, 4143.7306575072525, 4391.209582275189, 4393.406890069848, 4396.406485124515, 4408.25059739845, 4264.402983582301, 4267.594578515067, 4271.829126834923, 4290.806913688892, 4346.174226896911, 4106.1992911382295, 4181.728187667792, 4196.328587426027, 4241.257779888507, 4253.383259427396, 4521.706089104197, 4527.3435351873795, 4541.682082254243, 4552.408186935711, 4610.05493873755, 4909.852704418873, 4910.705357678551, 4915.729634870117, 4942.20924652054, 4996.25056241563, 4825.469198388175, 4907.607391408679, 4956.1822299320565, 4956.430010548797, 4966.751662416901, 4635.351050852917, 4641.164590373698, 4642.489326174118, 4647.790849411785, 4699.365585645894, 5066.0, 5066.645334826527, 5069.822556210515, 5071.264666623319, 5121.206213036985, 4672.486026537078, 4770.141374552544, 4801.567858892717, 4822.42130944289, 4901.705897646153, 4875.293082503017, 4888.437829649564, 4896.094222568836, 4905.852824415273, 4969.875753106129, 4860.0, 4874.293227481995, 4881.341018962466, 4881.9023619528125, 4934.284528743312, 4752.049590081998, 4793.209120495079, 4801.975990120027, 4871.147549178951, 4881.438634557, 4137.958620413758, 4158.480189976253, 4158.875233742932, 4160.54234034256, 4163.604457576586, 3625.8549658013912, 3639.0720366306423, 3679.7608155469807, 3714.981425092857, 3736.23407201523, 4244.830206791756, 4248.978755106266, 4250.489941207075, 4290.099079193342, 4310.202612516695, 4858.7570621468785, 4908.582770464551, 4914.213725803854, 4920.532549407801, 4970.751462426864, 4216.262154123016, 4217.219814334358, 4222.894427639334, 4262.957370426318, 4266.466691663542, 4412.933805992912, 4416.779161041935, 4423.424954755926, 4459.063596644676, 4470.463544374631, 4674.719516829001, 4681.695689780221, 4682.110399024311, 4687.156311863897, 4698.107359601665, 4850.975745121252, 4851.442084160367, 4863.416390033219, 4899.902001959869, 4919.262110683426, 4643.605293549951, 4653.999390131138, 4657.836975705834, 4709.576138147583, 4711.363965864564, 4805.975970120127, 4806.735629540409, 4813.7352445615825, 4816.277558366273, 4865.756712164307, 4796.6882152661365, 4858.319835223142, 4892.951070489249, 4938.728369939688, 4948.579370753526, 4835.540623640749, 4857.149998750276, 4864.17309057454, 4907.975460122677, 4917.164082105979, 4807.4231092267755, 4833.05755377698, 4842.491538388559, 4854.732989685601, 4919.0899683558655, 4530.29780384041, 4701.341812146371, 4735.100330937111, 4756.571908528249, 4771.355866957916, 4827.08285425768, 4845.0, 4846.36997190363, 4880.560749532559, 4909.803607855789, 4708.858734237979, 4729.574338309569, 4746.121967436035, 4747.928781068218, 4807.639427043056], "value": 4522.640886562928, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.8468671115444, 563.3774678979727, 567.6225310168738, 581.2298704216911, 584.2900875436344, 27771.861140694295, 36888.631113688854, 37947.810260948725, 38028.61971380285, 41928.79035604825, 39239.41140882882, 39676.80161599188, 39716.205675886464, 39725.40411893824, 39730.404043939365, 7703.075630924283, 7830.56931868747, 7837.373010159174, 7840.0591928968315, 7840.13758486566, 7706.6146692665425, 7784.299413052824, 7794.922050779476, 7799.103103143126, 7804.882926756075, 7761.41789365797, 7813.140554538996, 7826.804329891741, 7838.608069596526, 7851.489653172554, 7752.379809615274, 7782.610869456533, 7791.727289544839, 7818.296353328199, 7818.569978651172, 7270.382017528516, 7328.230535793718, 7330.743423980189, 7332.523385979895, 7334.853302933911, 36534.63465365339, 36891.12643172482, 36907.63092369069, 36937.44593831095, 36954.26091478182, 2964.0, 2971.777116716233, 2995.7902946793733, 3024.894128705507, 3059.908202753899, 28171.85914070437, 28907.67561730386, 28969.275768105756, 29127.0, 29583.0, 3448.121889868481, 3449.413599688059, 3504.7721898076543, 3514.876979305712, 3556.590992035897, 253140.0, 255226.0, 255424.0, 255447.0, 256165.7191714048, 30864.0, 51231.487685123415, 51568.74215628898, 51641.225381619304, 51667.48332516702, 31031.0, 32792.0, 32951.0, 33013.17467063294, 33020.66979330224, 11389.829152562717, 11453.942730286295, 11469.483873225725, 11495.942520287344, 11502.827457588142, 9455.385399948982, 9536.523173841419, 9555.665551705795, 9556.187724043533, 9556.378835375679, 7581.924180758232, 7622.237776222444, 7642.923570764332, 7682.76951691439, 7688.538687678757, 2976.2113040044405, 3002.384511175204, 3038.5897903782707, 3045.193023848682, 3067.5858759067687, 779.3998621061864, 867.7049803066988, 888.5779254854021, 892.6697122064877, 898.8247291778172, 25160.370990725012, 26889.193324200307, 26938.595921061198, 27175.32061698434, 27193.32016699559, 694.4236283884364, 852.7910661887877, 887.6064578611647, 918.2929144558786, 936.5457752989795, 13397.26315052662, 14649.414023439156, 15892.52322430329, 15956.48413400725, 16497.505074847773, 376186.0, 378380.0, 378392.0, 378436.0, 379394.0, 48560.27159592609, 49212.26181607279, 49256.75371623119, 49284.26073608899, 49704.25443618349, 997.2221667099674, 1087.4508373271447, 1110.555777688947, 1116.2911551165068, 1124.9268797528134, 25829.483410332065, 27568.58647120335, 28419.289517762223, 28809.135725928256, 29183.70816291895, 41066.0, 41754.58245417507, 41786.58213417827, 41796.0, 41803.0, 316179.0, 316539.0, 316764.0, 320131.0, 392286.0, 36256.27487450183, 36987.44518832274, 37110.99001634853, 37139.44290835586, 37799.244015118995, 41012.58987410088, 41351.7932410336, 41556.792216038724, 42297.0, 42451.3632295522, 35185.648143518236, 35472.29055418826, 35622.28755424929, 35885.0, 36135.63864361323, 34933.47599786056, 35260.294794104484, 35783.0, 35911.1022224436, 36201.0949726259, 34197.31605367829, 34474.65525344714, 34610.82694586511, 34648.82675586606, 35673.1081722959, 29413.85293073521, 31586.0, 31959.360812784078, 32074.35851283008, 32101.839490802395, 24305.878470607535, 24587.0, 24654.753452465964, 24945.981738342307, 25041.74958250394], "value": 16153.007937165328, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.7911894625333, 362.20857426523065, 364.39146625136027, 371.31863031337485, 384.3216722484813, 11606.59376921808, 14845.183514906676, 16779.077150756704, 16958.40645577405, 17607.559811004732, 14648.80979042556, 14852.777208341857, 14969.625759356022, 14983.850161498382, 15029.549113526611, 4905.754712264382, 5079.7714102865275, 5318.813841515548, 5348.411674715778, 5409.161579955094, 3345.7156141727983, 5328.973355133238, 5413.837584872441, 5440.401555828856, 5449.100898351767, 6600.306967768387, 6905.792826215222, 6926.757563485279, 6936.271691472399, 7042.929570704278, 6652.301508341651, 6786.660666966682, 6882.483813713991, 6886.24251332353, 6891.448684105259, 6345.746170153188, 6640.037194606755, 6682.465402767767, 6688.264290927994, 6688.29772873851, 22587.774122258896, 22965.196218132452, 23067.423314417105, 23151.0, 23279.3016209514, 1798.9370372037047, 2136.722226110596, 2260.3219034289646, 2420.4311986683115, 2615.777658898996, 13242.404091815892, 13926.860731392657, 14140.151590904476, 15021.399144034225, 15121.09273443597, 1807.2228941555127, 1879.8872067675984, 1893.0440127735544, 1924.8171423714725, 1949.4931317857458, 58505.41494585085, 73538.0, 78481.60759196225, 83527.5823620878, 84000.57999709963, 15000.0, 15584.532464026097, 16219.594510137105, 16316.184190790413, 16390.26243819031, 27661.0, 27745.861270693516, 27776.16671499858, 27809.582856257173, 28016.859915700697, 8153.79615509617, 8163.714270000641, 8170.836583268301, 8213.95893020543, 8578.78553036179, 6465.1595292611655, 6618.205815302098, 6659.633720145439, 6722.394984451423, 7064.752733654297, 8088.9039904859565, 8185.386096042821, 8305.626246818907, 8312.501249925024, 8369.916300837036, 3181.42605677701, 3269.836508174582, 3283.0, 3362.613299470592, 3369.2419205678525, 718.4073139659697, 754.5661244784271, 771.7530390275058, 781.6326326626477, 810.951342919415, 15614.219289035504, 15767.29047192879, 16966.575835603962, 17306.48080557585, 17342.479725608253, 806.5322113174345, 808.2442915873677, 820.7742870710603, 826.8677011678103, 838.1669955550201, 8961.283097352196, 9142.680006199884, 9540.379875308083, 9555.522223888778, 9680.806383872283, 373133.0, 375008.0, 391072.0, 391243.0, 391298.0, 76929.0, 78452.60773696096, 78768.0, 78842.6057869707, 81813.18186818175, 897.7486303834937, 907.7912080221586, 917.5412293853074, 926.323783637948, 928.7817362919709, 14165.929170354082, 14548.85451145475, 14875.776863346842, 14893.957422980287, 14912.179830109448, 38394.80802595969, 39314.41028384634, 39438.408423873094, 39848.800755997196, 41156.382654259614, 48612.5138748608, 48646.27030594615, 48748.75625621849, 49186.50813491819, 51752.0, 7033.964830175816, 7289.0, 7491.0, 7572.356349710336, 7587.962060189664, 11234.494447749707, 11656.650300490659, 12018.098642602015, 12048.216865903689, 12158.0, 9916.652917147865, 9945.900540994788, 10144.695659129942, 10231.334963227366, 10446.843297350393, 9897.77300107499, 10243.334183278062, 10327.638532651321, 10631.0, 10655.520501577292, 10125.240606954361, 10286.382817031003, 10320.174386048948, 10661.8933810664, 10750.301230419998, 7258.782236532912, 7304.269573042653, 7420.6289685516585, 7503.5497870127965, 7604.391648667983, 8187.795305117182, 9838.754031148992, 10254.23093267993, 10563.68308950702, 10572.312799667998], "value": 9039.923256691936, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1226.760781647578, 1340.5308142150245, 1374.292239496659, 1431.8711315981566, 1652.8264532224116, 6488.44848187904, 6912.066870972417, 7096.077509923703, 7114.857702845947, 7229.168645605757, 7061.152661680592, 7129.6791644376, 7130.108736407949, 7131.572105673663, 7133.072700548922, 2113.334299695595, 2132.925347612834, 2135.1779564867484, 2138.8823614701187, 2140.2081229944943, 1999.8600097993146, 2058.238451772846, 2077.6883467479847, 2082.302428686392, 2089.8432617553744, 2079.080555679725, 2099.3701889433187, 2109.8523103382768, 2112.26070875194, 2142.882141482218, 2073.0567591745794, 2078.1271865816393, 2079.2826474866147, 2081.2091405266056, 2083.9270625528034, 2008.7187793708893, 2020.2626041494748, 2047.406252186864, 2053.240301088592, 2053.291614393047, 7102.857942841166, 7206.963965180193, 7300.853982920312, 7305.780826575211, 7330.670119844566, 4195.077083041726, 4311.245532031882, 4332.891677708051, 4339.175556644227, 4345.152695224432, 2693.9595906061427, 2797.8880844766186, 2853.5862299966598, 2864.8137871038525, 2867.985660071707, 883.3992884838308, 897.3075793111365, 912.0286894457381, 920.0569416348229, 921.1939552891221, 10025.54885030168, 10052.547635356426, 10052.648157314532, 10164.847527287096, 10189.541470633765, 3609.7473176877893, 3791.450239715226, 3816.37029890065, 3986.3223252047223, 4003.8999025024023, 7954.363650907913, 8016.198380161937, 8020.0, 8025.799355016171, 8042.235987581171, 4128.876133715933, 4129.91740165201, 4132.235536425771, 4132.8140233689555, 4134.049168691218, 3647.452882067711, 3712.832922518493, 3758.567764707094, 3782.508273924372, 3865.43951127085, 3305.2812537480377, 3313.8011719296437, 3425.2806910549066, 3434.6737059979264, 3436.106612280825, 998.256299057207, 1016.3241444439365, 1026.5329275179774, 1028.4395004722444, 1030.1501261459325, 648.8604949935786, 705.0, 770.7803276066406, 789.4118881433369, 793.9166387529342, 4617.607503362185, 4800.447948485899, 4822.059698358857, 4863.8540843774745, 5003.949170674126, 390.79092685413167, 804.4770898915751, 840.3487297344485, 880.8898887639045, 915.8809354783835, 3374.915627109342, 3464.0993341730828, 3492.9825350873084, 3522.348365552381, 3553.502509648651, 8563.100874408223, 8829.16122968317, 9040.8643870342, 9044.14080662336, 9225.308101892386, 9012.909870901338, 9027.36808423404, 9030.548472576345, 9040.819183616422, 9044.728658140266, 6890.965545172242, 6947.861042779217, 7059.541129826647, 7081.291870812981, 7109.502334836615, 4869.415670119467, 4885.120678277802, 4896.975515122402, 4919.532644398776, 5008.524190201925, 8100.311473524816, 8305.584720764058, 8322.54226017575, 8420.36847236472, 8474.491530508189, 8379.994400671958, 8580.613872375883, 8644.567771611268, 8722.215000649972, 8733.344999124964, 5819.0, 6335.4931605472375, 6370.1718776558755, 6526.314736952551, 6612.867742645217, 6980.057692211486, 7102.467314951503, 7108.2536333686285, 7108.715651373887, 7117.7152913882865, 5992.250968628921, 6001.0, 6040.848978775566, 6129.84675383119, 6143.1706719592285, 6048.36492168316, 6093.055576385667, 6108.908366374419, 6108.908366374597, 6123.142760013513, 5854.121881717776, 5868.823935281948, 5898.9115163273445, 5904.261967254093, 5925.762969481172, 4160.625543701081, 4238.87283381499, 4241.512226093915, 4270.615644592001, 4485.977570112129, 4125.381192821101, 4148.398482220001, 4185.874423767292, 4293.8711838644895, 4448.5773851484055], "value": 3853.079591999426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.06758642689107, 296.31551116919917, 299.9220202747285, 301.5958615455291, 305.5630448458706, 4735.668503204777, 4740.037476577139, 4769.332293478911, 4783.899703068292, 4804.543568361, 4735.668503204777, 4737.313089602006, 4755.334253204554, 4757.8810529736775, 4758.214894542396, 3570.3751843427426, 3589.9282014359696, 3760.247950409915, 3778.6032466590955, 3811.6950643948417, 3744.625537446247, 3751.249750050001, 3752.2495500899795, 3767.5478942526797, 3778.527684039495, 3718.7582807117587, 3730.5896351401325, 3734.7012239020814, 3742.419924911643, 3752.0807402186365, 3540.6105328413855, 3567.090391950062, 3710.5547334319785, 3723.515942927429, 3731.0, 3219.5170724391296, 3228.9354212915846, 3230.6607806180245, 3233.531137984979, 3252.8536215870345, 4539.251023581105, 4580.931286030712, 4609.67732258742, 4615.8153673853, 4622.468416132154, 4062.7968601569805, 4144.834206631731, 4145.398917156996, 4147.9170416591805, 4151.211269858717, 4879.048585525824, 4887.364642596475, 4896.485868983741, 4898.0, 4899.216125419951, 3519.63043880394, 3542.5040494330815, 3549.165946002713, 3551.0, 3557.2707594942976, 5096.464871188546, 5113.795448182068, 5125.513076257786, 5128.538431541142, 5135.974320128375, 3353.714934230569, 3526.806025668613, 3542.574891013095, 3553.200529880808, 3627.766559369827, 3564.198055437506, 3599.5680518337967, 3600.1179710970455, 3606.6213047630145, 3610.097475631092, 4073.9592604073578, 4080.2451546464, 4085.346344584852, 4092.304308267542, 4095.7712686194022, 4757.904841903142, 4958.528939750718, 4964.453910069889, 4968.677035992649, 4986.750662466862, 1237.461704158694, 1261.4323554400373, 1263.109507797001, 1264.3930913161735, 1269.9746005079846, 806.5563939833069, 812.5530957973091, 824.74432925792, 826.7395770332326, 827.1604321613567, 3988.025927166071, 4218.725782824106, 4269.466316710411, 4402.779861007001, 4413.13943780966, 5057.671251368649, 5064.784451731632, 5069.819977409676, 5107.489251074937, 5113.386393632788, 4404.955950440519, 4409.2724700424715, 4412.580804823601, 4423.800928958204, 4424.955750442518, 2627.1067836935536, 2669.172556507489, 2675.906343278015, 2693.6094266331274, 2700.770434513049, 4802.015586804697, 4810.518948105161, 4846.466888642247, 4866.2238283027145, 4866.48901865299, 5162.741862906914, 5175.844724658191, 5191.870203244949, 5224.085784987612, 5240.3973543042275, 4655.604273636711, 4736.502667219961, 4822.517748225219, 4829.0, 4860.416749990024, 5091.490850914879, 5097.9490205098955, 5097.992184611097, 5099.286099945936, 5130.793758683802, 4896.36347274852, 4943.283223932582, 4965.255211718271, 4997.800087996439, 5021.221710634857, 4597.770081510532, 4801.399825021872, 4828.637852161172, 4836.637252206028, 4855.5387238212315, 3614.927701446009, 3626.6192049835445, 3628.9637103628625, 3629.020164555608, 3635.4910312556794, 5080.110980578458, 5097.286379906742, 5100.846974590768, 5121.641485096007, 5121.9743901281745, 4718.083990287984, 4889.975550122226, 4892.483330167659, 4965.925511117264, 4977.950220497749, 4906.656534042726, 4906.828261010847, 4935.4570997190995, 4944.975275123601, 4948.505149485167, 4880.90238195227, 4880.90238195227, 4882.462929077869, 4890.633202509897, 4910.558049775537, 4688.765561721969, 4727.125481785881, 4981.227909674007, 4984.40187177541, 4986.700797951989, 4692.694974826625, 4844.8304309349005, 4869.074875773734, 4870.586000190024, 4877.463598966314], "value": 3722.3083685749193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.9699015049247, 607.5443417436921, 625.1279465146118, 706.1032488739302, 721.9639018049096, 13060.869391306083, 13924.0, 13932.651683707913, 13953.790693139586, 13959.720805583882, 13368.465261389558, 13497.662558436044, 13529.729405411887, 13538.0, 13543.848772854319, 599.0624672387721, 726.0, 730.8172956760797, 747.4095464582973, 748.2666986353377, 746.9775906722807, 748.2816496163689, 748.9400847932179, 749.2806905370851, 749.3181205103357, 793.2266040610388, 794.0907660728462, 794.2216627704853, 794.2573693596477, 794.566961006253, 637.687533108778, 775.3370867907914, 791.5290402210676, 794.3764145146033, 798.0064819299951, 560.4255637971072, 565.2482198675751, 565.8755073883739, 566.0546886699223, 566.0801198053164, 12624.495020199181, 12737.681557961125, 12740.80888786669, 12773.552925647558, 12791.360431978364, 11258.43707814606, 11550.653480395602, 11622.76754464906, 11720.882791172064, 12255.57105501312, 11732.589359372381, 11845.94077029618, 11946.163768536206, 11959.40202989847, 12102.939485302606, 3193.984030079858, 3288.62180849203, 3429.9828500857584, 3445.552078229839, 4055.3511438170044, 52015.73992130015, 54528.727356363364, 60908.69545652287, 61106.38893611007, 62159.68920155415, 9662.3719458235, 9669.492750372445, 9788.461634610165, 9817.214622830157, 9907.25695572835, 17414.825851741574, 17586.736198957027, 17625.118744063002, 17684.292628294723, 17861.553461163574, 6646.966765166143, 6672.2994085621285, 6718.49611279156, 6844.0, 6940.13248343957, 3542.9645703542633, 3699.6300369962787, 3711.3690672585185, 3753.305638456894, 3779.622037796253, 2822.971770282312, 2956.645202575705, 2957.0685234151174, 2960.807547509405, 2964.584958105867, 464.61901240982746, 476.3045952908792, 481.40546425164894, 570.1390899741338, 599.0594766217084, 399.8720409468942, 447.8678789757029, 451.2193904545155, 474.890775121717, 478.9712617242977, 6662.367075127855, 7105.944443889177, 7261.818454538678, 7455.701771929171, 7745.186755390603, 448.34765416319175, 496.13919849061847, 501.2981825444407, 507.0821812519278, 513.3019094032113, 3427.708644765173, 3459.377312083849, 3513.4202856528386, 3587.157018100744, 3673.485712000322, 51168.744156279725, 51797.74101129545, 53030.46969530333, 53150.468495314555, 101556.76648502784, 73611.0, 74857.62571187218, 75016.0, 75396.62301688566, 75544.62227688826, 68136.6593167031, 69677.30385355609, 70196.29803701898, 71412.2858771416, 71956.0, 7152.642367881481, 7163.928360716326, 7307.7807665770115, 7427.962860185665, 7437.925620743939, 34295.31409371849, 41960.0, 42349.0, 42740.572594274905, 43042.0, 56715.43284567101, 58424.70787646034, 60591.15270778239, 62381.68809155925, 66745.0, 10085.142762864945, 10201.84697229557, 10258.58965641366, 10280.485975701335, 10330.638427654996, 11000.284981476178, 11003.889961100607, 11034.779304414027, 11241.88758112441, 11842.348670823187, 10400.479976001321, 10790.67627971162, 10905.618303359663, 10933.726656833325, 10988.285761425483, 10493.842592360968, 11047.834282485608, 11244.0, 12257.509699611915, 12310.50757969707, 10614.522346494581, 10731.517081731185, 11310.547578096784, 11311.717207069889, 11429.942850285695, 8754.343424243334, 8962.865557016781, 9056.411333263317, 9147.222486088756, 9548.045195480674, 8996.595153218252, 9422.05342195353, 9476.945144485031, 9570.712878613653, 9585.808283834423], "value": 6141.281510341652, "unit": "score"}], "name": "dromaeo_css", "value": 6224.775169415418, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 133.0, 114.0, 116.0, 116.0, 113.0, 117.0, 116.0, 116.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 149.0, 191.0, 128.0, 154.0, 147.0, 125.0, 190.0, 123.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 160.0, 163.0, 161.0, 162.0, 161.0, 162.0, 160.0, 168.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 173.0, 77.0, 78.0, 75.0, 76.0, 154.0, 76.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 148.0, 84.0, 82.0, 82.0, 131.0, 83.0, 84.0, 142.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 151.0, 147.0, 147.0, 147.0, 148.0, 146.0, 146.0, 146.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 94.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 64.0, 66.0, 68.0, 67.0, 67.0, 68.0, 66.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 67.0, 66.0, 65.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 82.0, 83.0, 83.0, 91.0, 85.0, 82.0, 82.0, 82.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 133.0, 128.0, 142.0, 134.0, 151.0, 133.0, 131.0, 174.0, 151.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 173.0, 174.0, 174.0, 177.0, 173.0, 176.0, 175.0, 173.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 69.0, 71.0, 70.0, 73.0, 73.0, 68.0, 72.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1586.0000000000002, "alertThreshold": 2.0}]} 17:49:56 INFO - Return code: 0 17:49:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:49:57 INFO - # TBPL SUCCESS # 17:49:57 INFO - Running post-action listener: _resource_record_post_action 17:49:57 INFO - [mozharness: 2016-08-31 00:49:57.030784Z] Finished run-tests step (success) 17:49:57 INFO - Running post-run listener: _resource_record_post_run 17:49:57 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 8192; Write bytes: 411336704; Read time: 152; Write time: 281240 17:49:57 INFO - TinderboxPrint: CPU usage
13.0% 17:49:57 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 152 17:49:57 INFO - TinderboxPrint: I/O write bytes / time
411,336,704 / 281,240 17:49:57 INFO - TinderboxPrint: CPU idle
8,353.6 (86.9%) 17:49:57 INFO - TinderboxPrint: CPU user
1,201.3 (12.5%) 17:49:57 INFO - TinderboxPrint: Swap in / out
0 / 0 17:49:57 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 120623104; Read time: 0; Write time: 90732 17:49:57 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290177024; Read time: 140; Write time: 190500 17:49:57 INFO - Running post-run listener: _upload_blobber_files 17:49:57 INFO - Blob upload gear active. 17:49:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:49:57 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 17:49:57 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:49:57 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:49:58 INFO - (blobuploader) - INFO - Open directory for files ... 17:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:49:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:49:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:49:58 INFO - (blobuploader) - INFO - Done attempting. 17:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:03 INFO - (blobuploader) - INFO - Done attempting. 17:50:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:50:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:50:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:50:04 INFO - (blobuploader) - INFO - Done attempting. 17:50:04 INFO - (blobuploader) - INFO - Iteration through files over. 17:50:04 INFO - Return code: 0 17:50:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:50:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:50:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1055e8e9b54ede721cb34ff8e6dfb8216d7eb4c1a08bb1c53e35b5b518b491b7c37eeafafef6b5626b7d4fc1d11aa9e07fa558e2b720b2e36977e6bbca0be98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6e750f47b422019e3c44f5ffff50b47c67f92dc42ee1b84fea461298e68fa35349a906a405624f05af8473f9d16e6c8a884301c95ca5b316f977d867299baca7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ce52e4c94f3545acbb5e67276587c7d25bf13b22e0a962ea119897890b968f37c169f14f406b6507fa6a110dc6b2f613ea8f9a13ec947ba9ae29581fa0323f6"} 17:50:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:50:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:50:04 INFO - Contents: 17:50:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1055e8e9b54ede721cb34ff8e6dfb8216d7eb4c1a08bb1c53e35b5b518b491b7c37eeafafef6b5626b7d4fc1d11aa9e07fa558e2b720b2e36977e6bbca0be98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6e750f47b422019e3c44f5ffff50b47c67f92dc42ee1b84fea461298e68fa35349a906a405624f05af8473f9d16e6c8a884301c95ca5b316f977d867299baca7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ce52e4c94f3545acbb5e67276587c7d25bf13b22e0a962ea119897890b968f37c169f14f406b6507fa6a110dc6b2f613ea8f9a13ec947ba9ae29581fa0323f6"} 17:50:04 INFO - Running post-run listener: copy_logs_to_upload_dir 17:50:04 INFO - Copying logs to upload dir... 17:50:04 INFO - mkdir: /builds/slave/test/build/upload/logs 17:50:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.923378 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-08-30 17:50:03.665506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 17:50:03.669163) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1055e8e9b54ede721cb34ff8e6dfb8216d7eb4c1a08bb1c53e35b5b518b491b7c37eeafafef6b5626b7d4fc1d11aa9e07fa558e2b720b2e36977e6bbca0be98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6e750f47b422019e3c44f5ffff50b47c67f92dc42ee1b84fea461298e68fa35349a906a405624f05af8473f9d16e6c8a884301c95ca5b316f977d867299baca7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ce52e4c94f3545acbb5e67276587c7d25bf13b22e0a962ea119897890b968f37c169f14f406b6507fa6a110dc6b2f613ea8f9a13ec947ba9ae29581fa0323f6"} build_url:https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011139 build_url: 'https://queue.taskcluster.net/v1/task/B5Gjb4WeQimWkDH0lEjbrw/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/Fx-Team-Non-PGO/sha512/1055e8e9b54ede721cb34ff8e6dfb8216d7eb4c1a08bb1c53e35b5b518b491b7c37eeafafef6b5626b7d4fc1d11aa9e07fa558e2b720b2e36977e6bbca0be98b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6e750f47b422019e3c44f5ffff50b47c67f92dc42ee1b84fea461298e68fa35349a906a405624f05af8473f9d16e6c8a884301c95ca5b316f977d867299baca7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ce52e4c94f3545acbb5e67276587c7d25bf13b22e0a962ea119897890b968f37c169f14f406b6507fa6a110dc6b2f613ea8f9a13ec947ba9ae29581fa0323f6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 17:50:03.694569) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:50:03.694942) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472603351.964407-1871907597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004384 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 17:50:03.776878) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 17:50:03.777236) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 17:50:03.777579) ========= ========= Total master_lag: 0.14 =========