builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1468845898.0 results: success (0) buildid: 20160718030454 builduid: bd53cdf8946447a581b1df14c09f0c13 revision: 0fbdcd21fad76a00328e67875c6f40dc219235f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.000043) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.000467) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.000769) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.020190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.020546) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 05:44:58-- 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% 113M=0s 2016-07-18 05:44:58 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083787 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.119025) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.119351) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017067 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.169241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.169598) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0fbdcd21fad76a00328e67875c6f40dc219235f4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0fbdcd21fad76a00328e67875c6f40dc219235f4 --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=e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 05:44:58,946 truncating revision to first 12 chars 2016-07-18 05:44:58,946 Setting DEBUG logging. 2016-07-18 05:44:58,946 attempt 1/10 2016-07-18 05:44:58,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0fbdcd21fad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 05:44:59,261 unpacking tar archive at: mozilla-central-0fbdcd21fad7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.673614 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.856143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.856436) ========= script_repo_revision: 0fbdcd21fad76a00328e67875c6f40dc219235f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.856790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.857053) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 05:44:58.864438) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-18 05:44:58.864708) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:44:59 INFO - MultiFileLogger online at 20160718 05:44:59 in /builds/slave/test 05:44:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:44:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:44:59 INFO - {'append_to_log': False, 05:44:59 INFO - 'base_work_dir': '/builds/slave/test', 05:44:59 INFO - 'blob_upload_branch': 'Firefox', 05:44:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:44:59 INFO - 'branch': 'Firefox', 05:44:59 INFO - 'buildbot_json_path': 'buildprops.json', 05:44:59 INFO - 'config_files': ('talos/linux_config.py',), 05:44:59 INFO - 'default_actions': ('clobber', 05:44:59 INFO - 'read-buildbot-config', 05:44:59 INFO - 'download-and-extract', 05:44:59 INFO - 'populate-webroot', 05:44:59 INFO - 'create-virtualenv', 05:44:59 INFO - 'install', 05:44:59 INFO - 'run-tests'), 05:44:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:44:59 INFO - 'download_minidump_stackwalk': True, 05:44:59 INFO - 'download_symbols': 'ondemand', 05:44:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:44:59 INFO - 'tooltool.py': '/tools/tooltool.py', 05:44:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:44:59 INFO - '/tools/misc-python/virtualenv.py')}, 05:44:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:44:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:44:59 INFO - 'installer_path': 'installer.exe', 05:44:59 INFO - 'log_level': 'info', 05:44:59 INFO - 'log_name': 'talos', 05:44:59 INFO - 'log_to_console': True, 05:44:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:44:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:44:59 INFO - 'opt_config_files': (), 05:44:59 INFO - 'pip_index': False, 05:44:59 INFO - 'sps_profile': False, 05:44:59 INFO - 'sps_profile_interval': 0, 05:44:59 INFO - 'suite': 'dromaeojs', 05:44:59 INFO - 'system_bits': '32', 05:44:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:44:59 INFO - 'title': 'talos-linux64-ix-067', 05:44:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:44:59 INFO - 'use_talos_json': True, 05:44:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:44:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:44:59 INFO - 'work_dir': 'build'} 05:44:59 INFO - ##### 05:44:59 INFO - ##### Running clobber step. 05:44:59 INFO - ##### 05:44:59 INFO - Running pre-action listener: _resource_record_pre_action 05:44:59 INFO - Running main action method: clobber 05:44:59 INFO - rmtree: /builds/slave/test/build 05:44:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:00 INFO - Running post-action listener: _resource_record_post_action 05:45:00 INFO - ##### 05:45:00 INFO - ##### Finished clobber step (success) 05:45:00 INFO - ##### 05:45:00 INFO - ##### 05:45:00 INFO - ##### Running read-buildbot-config step. 05:45:00 INFO - ##### 05:45:00 INFO - Running pre-action listener: _resource_record_pre_action 05:45:00 INFO - Running main action method: read_buildbot_config 05:45:00 INFO - Using buildbot properties: 05:45:00 INFO - { 05:45:00 INFO - "project": "", 05:45:00 INFO - "product": "firefox", 05:45:00 INFO - "script_repo_revision": "production", 05:45:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:45:00 INFO - "repository": "", 05:45:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:45:00 INFO - "buildid": "20160718030454", 05:45:00 INFO - "stage_platform": "linux64-pgo", 05:45:00 INFO - "basedir": "/builds/slave/test", 05:45:00 INFO - "nightly_build": "True", 05:45:00 INFO - "buildnumber": 799, 05:45:00 INFO - "slavename": "talos-linux64-ix-067", 05:45:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:45:00 INFO - "platform": "ubuntu64_hw", 05:45:00 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:45:00 INFO - "branch": "mozilla-central", 05:45:00 INFO - "repo_path": "mozilla-central", 05:45:00 INFO - "revision": "0fbdcd21fad76a00328e67875c6f40dc219235f4", 05:45:00 INFO - "pgo_build": "True", 05:45:00 INFO - "builduid": "bd53cdf8946447a581b1df14c09f0c13", 05:45:00 INFO - "slavebuilddir": "test" 05:45:00 INFO - } 05:45:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:45:00 INFO - Running post-action listener: _resource_record_post_action 05:45:00 INFO - ##### 05:45:00 INFO - ##### Finished read-buildbot-config step (success) 05:45:00 INFO - ##### 05:45:00 INFO - ##### 05:45:00 INFO - ##### Running download-and-extract step. 05:45:00 INFO - ##### 05:45:00 INFO - Running pre-action listener: _resource_record_pre_action 05:45:00 INFO - Running main action method: download_and_extract 05:45:00 INFO - mkdir: /builds/slave/test/build/tests 05:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:00 INFO - https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:45:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:45:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:45:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:45:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:45:02 INFO - Downloaded 1361 bytes. 05:45:02 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:45:02 INFO - Using the following test package requirements: 05:45:02 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:45:02 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:45:02 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:45:02 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'jsshell-linux-x86_64.zip'], 05:45:02 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:45:02 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:45:02 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:45:02 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:45:02 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:45:02 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:45:02 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:45:02 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:02 INFO - https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:45:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:45:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:45:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:45:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:45:03 INFO - Downloaded 24334071 bytes. 05:45:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:45:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:45:05 INFO - Return code: 0 05:45:05 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:05 INFO - https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:45:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:45:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:45:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:45:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:45:21 INFO - Downloaded 24334071 bytes. 05:45:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:45:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:45:22 INFO - Return code: 0 05:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:22 INFO - https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:45:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:45:24 INFO - Downloaded 14403317 bytes. 05:45:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:45:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:45:24 INFO - Return code: 0 05:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:24 INFO - https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:45:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:45:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:45:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:45:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:45:25 INFO - Downloaded 60799152 bytes. 05:45:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:45:25 INFO - mkdir: /builds/slave/test/properties 05:45:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:45:25 INFO - Writing to file /builds/slave/test/properties/build_url 05:45:25 INFO - Contents: 05:45:25 INFO - build_url:https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:45:26 INFO - Running post-action listener: _resource_record_post_action 05:45:26 INFO - Running post-action listener: set_extra_try_arguments 05:45:26 INFO - ##### 05:45:26 INFO - ##### Finished download-and-extract step (success) 05:45:26 INFO - ##### 05:45:26 INFO - ##### 05:45:26 INFO - ##### Running populate-webroot step. 05:45:26 INFO - ##### 05:45:26 INFO - Running pre-action listener: _resource_record_pre_action 05:45:26 INFO - Running main action method: populate_webroot 05:45:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:45:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:45:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:45:26 INFO - '--tppagecycles', 05:45:26 INFO - '7'], 05:45:26 INFO - 'tests': ['tsvgm']}}, 05:45:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:45:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:45:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:45:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:45:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['damp', 'tps']}, 05:45:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'tests': ['damp', 'tps']}, 05:45:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['dromaeo_dom']}, 05:45:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:45:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['basic_compositor_video']}, 05:45:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:45:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['a11yr', 05:45:26 INFO - 'ts_paint', 05:45:26 INFO - 'tpaint', 05:45:26 INFO - 'sessionrestore', 05:45:26 INFO - 'sessionrestore_no_auto_restore', 05:45:26 INFO - 'tabpaint']}, 05:45:26 INFO - 'other-e10s': {'tests': ['a11yr', 05:45:26 INFO - 'ts_paint', 05:45:26 INFO - 'tpaint', 05:45:26 INFO - 'sessionrestore', 05:45:26 INFO - 'sessionrestore_no_auto_restore', 05:45:26 INFO - 'tabpaint']}, 05:45:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:45:26 INFO - 'ts_paint', 05:45:26 INFO - 'tpaint', 05:45:26 INFO - 'sessionrestore', 05:45:26 INFO - 'sessionrestore_no_auto_restore', 05:45:26 INFO - 'tabpaint']}, 05:45:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:45:26 INFO - 'ts_paint', 05:45:26 INFO - 'tpaint', 05:45:26 INFO - 'sessionrestore', 05:45:26 INFO - 'sessionrestore_no_auto_restore', 05:45:26 INFO - 'tabpaint']}, 05:45:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['a11yr', 05:45:26 INFO - 'ts_paint', 05:45:26 INFO - 'tpaint', 05:45:26 INFO - 'sessionrestore', 05:45:26 INFO - 'sessionrestore_no_auto_restore', 05:45:26 INFO - 'tabpaint']}, 05:45:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['a11yr', 05:45:26 INFO - 'ts_paint', 05:45:26 INFO - 'tpaint', 05:45:26 INFO - 'sessionrestore', 05:45:26 INFO - 'sessionrestore_no_auto_restore', 05:45:26 INFO - 'tabpaint']}, 05:45:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['tsvgx', 05:45:26 INFO - 'tsvgr_opacity', 05:45:26 INFO - 'tart', 05:45:26 INFO - 'tscrollx', 05:45:26 INFO - 'cart']}, 05:45:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:45:26 INFO - 'tsvgr_opacity', 05:45:26 INFO - 'tart', 05:45:26 INFO - 'tscrollx', 05:45:26 INFO - 'cart']}, 05:45:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'talos_options': ['--disable-e10s'], 05:45:26 INFO - 'tests': ['tp5o']}, 05:45:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'tests': ['tp5o']}, 05:45:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'talos_options': ['--disable-e10s', 05:45:26 INFO - '--xperf_path', 05:45:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:26 INFO - 'tests': ['tp5n']}, 05:45:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:26 INFO - 'talos_options': ['--xperf_path', 05:45:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:26 INFO - 'tests': ['tp5n']}}, 05:45:26 INFO - 'talos.zip': {'path': '', 05:45:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:45:26 INFO - Running post-action listener: _resource_record_post_action 05:45:26 INFO - ##### 05:45:26 INFO - ##### Finished populate-webroot step (success) 05:45:26 INFO - ##### 05:45:26 INFO - ##### 05:45:26 INFO - ##### Running create-virtualenv step. 05:45:26 INFO - ##### 05:45:26 INFO - Running pre-action listener: _resource_record_pre_action 05:45:26 INFO - Running main action method: create_virtualenv 05:45:26 INFO - Creating virtualenv /builds/slave/test/build/venv 05:45:26 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 05:45:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:45:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:45:26 INFO - Using real prefix '/usr' 05:45:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:45:27 INFO - Installing distribute.............................................................................................................................................................................................done. 05:45:29 INFO - Installing pip.................done. 05:45:29 INFO - Return code: 0 05:45:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:45:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:45:29 INFO - Using env: {'DISPLAY': ':0', 05:45:29 INFO - 'HOME': '/home/cltbld', 05:45:29 INFO - 'LANG': 'en_US.UTF-8', 05:45:29 INFO - 'LANGUAGE': 'en_US:en', 05:45:29 INFO - 'LOGNAME': 'cltbld', 05:45:29 INFO - 'MAIL': '/var/mail/cltbld', 05:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:29 INFO - 'MOZ_NO_REMOTE': '1', 05:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:29 INFO - 'NO_EM_RESTART': '1', 05:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:29 INFO - 'PWD': '/builds/slave/test', 05:45:29 INFO - 'SHELL': '/bin/bash', 05:45:29 INFO - 'SHLVL': '1', 05:45:29 INFO - 'TERM': 'linux', 05:45:29 INFO - 'TMOUT': '86400', 05:45:29 INFO - 'USER': 'cltbld', 05:45:29 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:29 INFO - '_': '/tools/buildbot/bin/python'} 05:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:29 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 05:45:29 INFO - Cleaning up... 05:45:29 INFO - Return code: 0 05:45:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:45:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:45:29 INFO - Using env: {'DISPLAY': ':0', 05:45:29 INFO - 'HOME': '/home/cltbld', 05:45:29 INFO - 'LANG': 'en_US.UTF-8', 05:45:29 INFO - 'LANGUAGE': 'en_US:en', 05:45:29 INFO - 'LOGNAME': 'cltbld', 05:45:29 INFO - 'MAIL': '/var/mail/cltbld', 05:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:29 INFO - 'MOZ_NO_REMOTE': '1', 05:45:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:29 INFO - 'NO_EM_RESTART': '1', 05:45:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:29 INFO - 'PWD': '/builds/slave/test', 05:45:29 INFO - 'SHELL': '/bin/bash', 05:45:29 INFO - 'SHLVL': '1', 05:45:29 INFO - 'TERM': 'linux', 05:45:29 INFO - 'TMOUT': '86400', 05:45:29 INFO - 'USER': 'cltbld', 05:45:29 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:29 INFO - '_': '/tools/buildbot/bin/python'} 05:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:29 INFO - Downloading/unpacking psutil>=3.1.1 05:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:31 INFO - Installing collected packages: psutil 05:45:31 INFO - Running setup.py install for psutil 05:45:31 INFO - building 'psutil._psutil_linux' extension 05:45:31 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 05:45:31 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 05:45:31 INFO - building 'psutil._psutil_posix' extension 05:45:31 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 05:45:31 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 05:45:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:32 INFO - Successfully installed psutil 05:45:32 INFO - Cleaning up... 05:45:32 INFO - Return code: 0 05:45:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:45: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 mozsystemmonitor==0.3 05:45:32 INFO - Using env: {'DISPLAY': ':0', 05:45:32 INFO - 'HOME': '/home/cltbld', 05:45:32 INFO - 'LANG': 'en_US.UTF-8', 05:45:32 INFO - 'LANGUAGE': 'en_US:en', 05:45:32 INFO - 'LOGNAME': 'cltbld', 05:45:32 INFO - 'MAIL': '/var/mail/cltbld', 05:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:32 INFO - 'MOZ_NO_REMOTE': '1', 05:45:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:32 INFO - 'NO_EM_RESTART': '1', 05:45:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:32 INFO - 'PWD': '/builds/slave/test', 05:45:32 INFO - 'SHELL': '/bin/bash', 05:45:32 INFO - 'SHLVL': '1', 05:45:32 INFO - 'TERM': 'linux', 05:45:32 INFO - 'TMOUT': '86400', 05:45:32 INFO - 'USER': 'cltbld', 05:45:32 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:32 INFO - '_': '/tools/buildbot/bin/python'} 05:45:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:45:34 INFO - Installing collected packages: mozsystemmonitor 05:45:34 INFO - Running setup.py install for mozsystemmonitor 05:45:34 INFO - Successfully installed mozsystemmonitor 05:45:34 INFO - Cleaning up... 05:45:34 INFO - Return code: 0 05:45:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:34 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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:34 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 05:45:34 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 05:45:34 INFO - Using env: {'DISPLAY': ':0', 05:45:34 INFO - 'HOME': '/home/cltbld', 05:45:34 INFO - 'LANG': 'en_US.UTF-8', 05:45:34 INFO - 'LANGUAGE': 'en_US:en', 05:45:34 INFO - 'LOGNAME': 'cltbld', 05:45:34 INFO - 'MAIL': '/var/mail/cltbld', 05:45:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:34 INFO - 'MOZ_NO_REMOTE': '1', 05:45:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:34 INFO - 'NO_EM_RESTART': '1', 05:45:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:34 INFO - 'PWD': '/builds/slave/test', 05:45:34 INFO - 'SHELL': '/bin/bash', 05:45:34 INFO - 'SHLVL': '1', 05:45:34 INFO - 'TERM': 'linux', 05:45:34 INFO - 'TMOUT': '86400', 05:45:34 INFO - 'USER': 'cltbld', 05:45:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:34 INFO - '_': '/tools/buildbot/bin/python'} 05:45:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 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 05:45:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:36 INFO - warning: no files found matching '*.txt' 05:45:36 INFO - no previously-included directories found matching 'build' 05:45:36 INFO - no previously-included directories found matching 'dist' 05:45:36 INFO - no previously-included directories found matching '.git*' 05:45:36 INFO - Installing collected packages: jsonschema, functools32 05:45:36 INFO - Running setup.py install for functools32 05:45:36 INFO - warning: no files found matching '*.txt' 05:45:36 INFO - no previously-included directories found matching 'build' 05:45:36 INFO - no previously-included directories found matching 'dist' 05:45:36 INFO - no previously-included directories found matching '.git*' 05:45:36 INFO - Successfully installed jsonschema functools32 05:45:36 INFO - Cleaning up... 05:45:37 INFO - Return code: 0 05:45:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:45:37 INFO - Using env: {'DISPLAY': ':0', 05:45:37 INFO - 'HOME': '/home/cltbld', 05:45:37 INFO - 'LANG': 'en_US.UTF-8', 05:45:37 INFO - 'LANGUAGE': 'en_US:en', 05:45:37 INFO - 'LOGNAME': 'cltbld', 05:45:37 INFO - 'MAIL': '/var/mail/cltbld', 05:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:37 INFO - 'MOZ_NO_REMOTE': '1', 05:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:37 INFO - 'NO_EM_RESTART': '1', 05:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:37 INFO - 'PWD': '/builds/slave/test', 05:45:37 INFO - 'SHELL': '/bin/bash', 05:45:37 INFO - 'SHLVL': '1', 05:45:37 INFO - 'TERM': 'linux', 05:45:37 INFO - 'TMOUT': '86400', 05:45:37 INFO - 'USER': 'cltbld', 05:45:37 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:37 INFO - '_': '/tools/buildbot/bin/python'} 05:45:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:37 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:45:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45: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 05:45:39 INFO - Downloading docopt-0.6.1.tar.gz 05:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:45:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:45:39 INFO - Running setup.py install for blobuploader 05:45:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:45:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:45:39 INFO - Running setup.py install for requests 05:45:40 INFO - Running setup.py install for docopt 05:45:40 INFO - Successfully installed blobuploader requests docopt 05:45:40 INFO - Cleaning up... 05:45:40 INFO - Return code: 0 05:45:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:40 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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:40 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 05:45:40 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 05:45:40 INFO - Using env: {'DISPLAY': ':0', 05:45:40 INFO - 'HOME': '/home/cltbld', 05:45:40 INFO - 'LANG': 'en_US.UTF-8', 05:45:40 INFO - 'LANGUAGE': 'en_US:en', 05:45:40 INFO - 'LOGNAME': 'cltbld', 05:45:40 INFO - 'MAIL': '/var/mail/cltbld', 05:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:40 INFO - 'MOZ_NO_REMOTE': '1', 05:45:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:40 INFO - 'NO_EM_RESTART': '1', 05:45:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:40 INFO - 'PWD': '/builds/slave/test', 05:45:40 INFO - 'SHELL': '/bin/bash', 05:45:40 INFO - 'SHLVL': '1', 05:45:40 INFO - 'TERM': 'linux', 05:45:40 INFO - 'TMOUT': '86400', 05:45:40 INFO - 'USER': 'cltbld', 05:45:40 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:40 INFO - '_': '/tools/buildbot/bin/python'} 05:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:40 INFO - Running setup.py (path:/tmp/pip-R2L8l8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:40 INFO - Running setup.py (path:/tmp/pip-Fr0oKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:40 INFO - Running setup.py (path:/tmp/pip-i7AbuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:40 INFO - Running setup.py (path:/tmp/pip-HABzGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:40 INFO - Running setup.py (path:/tmp/pip-VrOCIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:41 INFO - Running setup.py (path:/tmp/pip-EY1EZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:41 INFO - Running setup.py (path:/tmp/pip-ONX7Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:41 INFO - Running setup.py (path:/tmp/pip-g8usjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:41 INFO - Running setup.py (path:/tmp/pip-4wFkKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:41 INFO - Running setup.py (path:/tmp/pip-x91DJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:41 INFO - Running setup.py (path:/tmp/pip-eSNe7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:41 INFO - Running setup.py (path:/tmp/pip-j5zaz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:41 INFO - Running setup.py (path:/tmp/pip-In0BGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:41 INFO - Running setup.py (path:/tmp/pip-76cHjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:41 INFO - Running setup.py (path:/tmp/pip-tQpHjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:41 INFO - Running setup.py (path:/tmp/pip-iM48KB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:41 INFO - Running setup.py (path:/tmp/pip-0JROSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:45:41 INFO - Running setup.py install for manifestparser 05:45:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Running setup.py install for mozcrash 05:45:42 INFO - Running setup.py install for mozdebug 05:45:42 INFO - Running setup.py install for mozdevice 05:45:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Running setup.py install for mozfile 05:45:42 INFO - Running setup.py install for mozhttpd 05:45:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Running setup.py install for mozinfo 05:45:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Running setup.py install for mozInstall 05:45:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Running setup.py install for mozleak 05:45:42 INFO - Running setup.py install for mozlog 05:45:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:45:42 INFO - Running setup.py install for moznetwork 05:45:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:45:43 INFO - Running setup.py install for mozprocess 05:45:43 INFO - Running setup.py install for mozprofile 05:45:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:45:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:45:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:45:43 INFO - Running setup.py install for mozrunner 05:45:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:45:43 INFO - Running setup.py install for mozscreenshot 05:45:43 INFO - Running setup.py install for moztest 05:45:43 INFO - Running setup.py install for mozversion 05:45:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:45:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:45:43 INFO - Cleaning up... 05:45:43 INFO - Return code: 0 05:45:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:43 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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:43 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 05:45:43 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 05:45:43 INFO - Using env: {'DISPLAY': ':0', 05:45:43 INFO - 'HOME': '/home/cltbld', 05:45:43 INFO - 'LANG': 'en_US.UTF-8', 05:45:43 INFO - 'LANGUAGE': 'en_US:en', 05:45:43 INFO - 'LOGNAME': 'cltbld', 05:45:43 INFO - 'MAIL': '/var/mail/cltbld', 05:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:43 INFO - 'MOZ_NO_REMOTE': '1', 05:45:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:43 INFO - 'NO_EM_RESTART': '1', 05:45:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:43 INFO - 'PWD': '/builds/slave/test', 05:45:43 INFO - 'SHELL': '/bin/bash', 05:45:43 INFO - 'SHLVL': '1', 05:45:43 INFO - 'TERM': 'linux', 05:45:43 INFO - 'TMOUT': '86400', 05:45:43 INFO - 'USER': 'cltbld', 05:45:43 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:43 INFO - '_': '/tools/buildbot/bin/python'} 05:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:45:43 INFO - Running setup.py (path:/tmp/pip-7NMP_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:45:43 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)) 05:45:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:45:43 INFO - Running setup.py (path:/tmp/pip-xMz4jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:45:44 INFO - Running setup.py (path:/tmp/pip-IvQxxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:45:44 INFO - Running setup.py (path:/tmp/pip-RB86V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:45:44 INFO - Running setup.py (path:/tmp/pip-ijFTIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:45:44 INFO - Running setup.py (path:/tmp/pip-rEjjoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:45:44 INFO - Running setup.py (path:/tmp/pip-9YWy8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:45:44 INFO - Running setup.py (path:/tmp/pip-e73YjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:45:44 INFO - Running setup.py (path:/tmp/pip-5qasAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:45:44 INFO - Running setup.py (path:/tmp/pip-2ERsRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:45:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:45:44 INFO - Running setup.py (path:/tmp/pip-ZqeSvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:45:44 INFO - Running setup.py (path:/tmp/pip-6YqxbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:45:44 INFO - Running setup.py (path:/tmp/pip-r5Mint-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:45:44 INFO - Running setup.py (path:/tmp/pip-XSoz36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:45:44 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)) 05:45:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:44 INFO - Running setup.py (path:/tmp/pip-zuuvSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:45:45 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)) 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:45:45 INFO - Running setup.py (path:/tmp/pip-prYgoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:45:45 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)) 05:45:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:45:45 INFO - Running setup.py (path:/tmp/pip-OcGeFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:45:45 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)) 05:45:45 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)) 05:45:45 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)) 05:45:45 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)) 05:45:45 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)) 05:45:45 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)) 05:45:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:47 INFO - Downloading blessings-1.6.tar.gz 05:45:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:45:47 INFO - Installing collected packages: blessings 05:45:47 INFO - Running setup.py install for blessings 05:45:47 INFO - Successfully installed blessings 05:45:47 INFO - Cleaning up... 05:45:47 INFO - Return code: 0 05:45:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:47 INFO - Reading from file tmpfile_stdout 05:45:47 INFO - Current package versions: 05:45:47 INFO - argparse == 1.2.1 05:45:47 INFO - blessings == 1.6 05:45:47 INFO - blobuploader == 1.2.4 05:45:47 INFO - docopt == 0.6.1 05:45:47 INFO - functools32 == 3.2.3-2 05:45:47 INFO - jsonschema == 2.5.1 05:45:47 INFO - manifestparser == 1.1 05:45:47 INFO - mozInstall == 1.12 05:45:47 INFO - mozcrash == 0.17 05:45:47 INFO - mozdebug == 0.1 05:45:47 INFO - mozdevice == 0.48 05:45:47 INFO - mozfile == 1.2 05:45:47 INFO - mozhttpd == 0.7 05:45:47 INFO - mozinfo == 0.9 05:45:47 INFO - mozleak == 0.1 05:45:47 INFO - mozlog == 3.2 05:45:47 INFO - moznetwork == 0.27 05:45:47 INFO - mozprocess == 0.23 05:45:47 INFO - mozprofile == 0.28 05:45:47 INFO - mozrunner == 6.12 05:45:47 INFO - mozscreenshot == 0.1 05:45:47 INFO - mozsystemmonitor == 0.3 05:45:47 INFO - moztest == 0.7 05:45:47 INFO - mozversion == 1.4 05:45:47 INFO - psutil == 3.1.1 05:45:47 INFO - requests == 1.2.3 05:45:47 INFO - wsgiref == 0.1.2 05:45:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:47 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 05:45:47 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 05:45:47 INFO - Using env: {'DISPLAY': ':0', 05:45:47 INFO - 'HOME': '/home/cltbld', 05:45:47 INFO - 'LANG': 'en_US.UTF-8', 05:45:47 INFO - 'LANGUAGE': 'en_US:en', 05:45:47 INFO - 'LOGNAME': 'cltbld', 05:45:47 INFO - 'MAIL': '/var/mail/cltbld', 05:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:47 INFO - 'MOZ_NO_REMOTE': '1', 05:45:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:47 INFO - 'NO_EM_RESTART': '1', 05:45:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:47 INFO - 'PWD': '/builds/slave/test', 05:45:47 INFO - 'SHELL': '/bin/bash', 05:45:47 INFO - 'SHLVL': '1', 05:45:47 INFO - 'TERM': 'linux', 05:45:47 INFO - 'TMOUT': '86400', 05:45:47 INFO - 'USER': 'cltbld', 05:45:47 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:47 INFO - '_': '/tools/buildbot/bin/python'} 05:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 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)) 05:45:47 INFO - Cleaning up... 05:45:47 INFO - Return code: 0 05:45:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:47 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 0x2892030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b53180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', '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 05:45:47 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 05:45:47 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 05:45:47 INFO - Using env: {'DISPLAY': ':0', 05:45:47 INFO - 'HOME': '/home/cltbld', 05:45:47 INFO - 'LANG': 'en_US.UTF-8', 05:45:47 INFO - 'LANGUAGE': 'en_US:en', 05:45:47 INFO - 'LOGNAME': 'cltbld', 05:45:47 INFO - 'MAIL': '/var/mail/cltbld', 05:45:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:47 INFO - 'MOZ_NO_REMOTE': '1', 05:45:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:47 INFO - 'NO_EM_RESTART': '1', 05:45:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:47 INFO - 'PWD': '/builds/slave/test', 05:45:47 INFO - 'SHELL': '/bin/bash', 05:45:47 INFO - 'SHLVL': '1', 05:45:47 INFO - 'TERM': 'linux', 05:45:47 INFO - 'TMOUT': '86400', 05:45:47 INFO - 'USER': 'cltbld', 05:45:47 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:45:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:47 INFO - '_': '/tools/buildbot/bin/python'} 05:45:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:45:47 INFO - Cleaning up... 05:45:47 INFO - Return code: 0 05:45:47 INFO - Running post-action listener: _resource_record_post_action 05:45:47 INFO - Running post-action listener: _start_resource_monitoring 05:45:47 INFO - Starting resource monitoring. 05:45:47 INFO - ##### 05:45:47 INFO - ##### Finished create-virtualenv step (success) 05:45:47 INFO - ##### 05:45:47 INFO - ##### 05:45:47 INFO - ##### Running install step. 05:45:47 INFO - ##### 05:45:47 INFO - Running pre-action listener: _resource_record_pre_action 05:45:47 INFO - Running main action method: install 05:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:45:48 INFO - Reading from file tmpfile_stdout 05:45:48 INFO - Detecting whether we're running mozinstall >=1.0... 05:45:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:45:48 INFO - Reading from file tmpfile_stdout 05:45:48 INFO - Output received: 05:45:48 INFO - Usage: mozinstall [options] installer 05:45:48 INFO - Options: 05:45:48 INFO - -h, --help show this help message and exit 05:45:48 INFO - -d DEST, --destination=DEST 05:45:48 INFO - Directory to install application into. [default: 05:45:48 INFO - "/builds/slave/test"] 05:45:48 INFO - --app=APP Application being installed. [default: firefox] 05:45:48 INFO - mkdir: /builds/slave/test/build/application 05:45:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:46:05 INFO - Reading from file tmpfile_stdout 05:46:05 INFO - Output received: 05:46:05 INFO - /builds/slave/test/build/application/firefox/firefox 05:46:05 INFO - Running post-action listener: _resource_record_post_action 05:46:05 INFO - ##### 05:46:05 INFO - ##### Finished install step (success) 05:46:05 INFO - ##### 05:46:05 INFO - ##### 05:46:05 INFO - ##### Running run-tests step. 05:46:05 INFO - ##### 05:46:05 INFO - Running pre-action listener: _resource_record_pre_action 05:46:05 INFO - Running main action method: run_tests 05:46:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:46:05 INFO - Python 2.7.3 05:46:05 INFO - Return code: 0 05:46:05 INFO - grabbing minidump binary from tooltool 05:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:05 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 0x2b513b0>, '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 0x2b522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b52740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:46:05 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 05:46:05 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 05:46:05 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 05:46:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:05 INFO - Return code: 0 05:46:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:46:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:46:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:46:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:46:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:46:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 05:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 05:46:05 INFO - Using env: {'DISPLAY': ':0', 05:46:05 INFO - 'HOME': '/home/cltbld', 05:46:05 INFO - 'LANG': 'en_US.UTF-8', 05:46:05 INFO - 'LANGUAGE': 'en_US:en', 05:46:05 INFO - 'LOGNAME': 'cltbld', 05:46:05 INFO - 'MAIL': '/var/mail/cltbld', 05:46:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:46:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:05 INFO - 'MOZ_NO_REMOTE': '1', 05:46:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:46:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:46:05 INFO - 'NO_EM_RESTART': '1', 05:46:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:05 INFO - 'PWD': '/builds/slave/test', 05:46:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:46:05 INFO - 'SHELL': '/bin/bash', 05:46:05 INFO - 'SHLVL': '1', 05:46:05 INFO - 'TERM': 'linux', 05:46:05 INFO - 'TMOUT': '86400', 05:46:05 INFO - 'USER': 'cltbld', 05:46:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875', 05:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:05 INFO - '_': '/tools/buildbot/bin/python'} 05:46:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 05:46:05 INFO - mozversion application_buildid: 20160718030454 05:46:05 INFO - mozversion application_changeset: 0fbdcd21fad76a00328e67875c6f40dc219235f4 05:46:05 INFO - mozversion application_display_name: Nightly 05:46:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:05 INFO - mozversion application_name: Firefox 05:46:05 INFO - mozversion application_remotingname: firefox 05:46:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:46:05 INFO - mozversion application_vendor: Mozilla 05:46:05 INFO - mozversion application_version: 50.0a1 05:46:05 INFO - mozversion platform_buildid: 20160718030454 05:46:05 INFO - mozversion platform_changeset: 0fbdcd21fad76a00328e67875c6f40dc219235f4 05:46:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:46:05 INFO - mozversion platform_version: 50.0a1 05:46:05 INFO - using testdate: 1468845965 05:46:05 INFO - actual date: 1468845965 05:46:05 INFO - starting webserver on 'localhost:58072' 05:46:05 INFO - SUITE-START | Running 2 tests 05:46:05 INFO - TEST-START | dromaeo_css 05:46:05 INFO - operating with platform_type : linux_ 05:46:05 INFO - Initialising browser for dromaeo_css test... 05:46:05 INFO - TEST-INFO | started process 12573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6c2Z5u/profile http://localhost:58072/getInfo.html) 05:46:08 INFO - PROCESS | 12573 | JavaScript error: , line 0: uncaught exception: undefined 05:46:09 INFO - PROCESS | 12573 | __metrics Screen width/height:1600/1200 05:46:09 INFO - PROCESS | 12573 | colorDepth:24 05:46:09 INFO - PROCESS | 12573 | Browser inner width/height: 1024/697 05:46:09 INFO - PROCESS | 12573 | __metrics 05:46:10 INFO - PROCESS | 12573 | 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" 05:46:10 INFO - PROCESS | 12573 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:46:11 INFO - TEST-INFO | 12573: exit 0 05:46:11 INFO - Browser initialized. 05:46:11 INFO - Running cycle 1/1 for dromaeo_css test... 05:46:11 INFO - TEST-INFO | started process 12656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6c2Z5u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:46:22 INFO - PROCESS | 12656 | RSS: Main: 157773824 05:46:22 INFO - PROCESS | 12656 | 05:47:14 INFO - PROCESS | 12656 | 1468846034284 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] 05:49:21 INFO - PROCESS | 12656 | Cycle 1(1): loaded http://localhost:58072/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58072/tests/dromaeo/cssquery-ext.html) 05:49:22 INFO - PROCESS | 12656 | RSS: Main: 258990080 05:49:22 INFO - PROCESS | 12656 | 05:52:25 INFO - PROCESS | 12656 | Cycle 1(1): loaded http://localhost:58072/tests/dromaeo/cssquery-ext.html (next: http://localhost:58072/tests/dromaeo/cssquery-jquery.html) 05:52:25 INFO - PROCESS | 12656 | RSS: Main: 246489088 05:52:25 INFO - PROCESS | 12656 | 05:55:27 INFO - PROCESS | 12656 | Cycle 1(1): loaded http://localhost:58072/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58072/tests/dromaeo/cssquery-mootools.html) 05:55:28 INFO - PROCESS | 12656 | RSS: Main: 237883392 05:55:28 INFO - PROCESS | 12656 | 05:58:30 INFO - PROCESS | 12656 | Cycle 1(1): loaded http://localhost:58072/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58072/tests/dromaeo/cssquery-prototype.html) 05:58:31 INFO - PROCESS | 12656 | RSS: Main: 236322816 05:58:31 INFO - PROCESS | 12656 | 06:01:33 INFO - PROCESS | 12656 | Cycle 1(1): loaded http://localhost:58072/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58072/tests/dromaeo/cssquery-yui.html) 06:01:34 INFO - PROCESS | 12656 | RSS: Main: 211828736 06:01:34 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | Cycle 1(1): loaded http://localhost:58072/tests/dromaeo/cssquery-yui.html (next: http://localhost:58072/tests/dromaeo/cssquery-dojo.html) 06:04:37 INFO - PROCESS | 12656 | RSS: Main: 273653760 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | __start_tp_report 06:04:37 INFO - PROCESS | 12656 | _x_x_mozilla_page_load 06:04:37 INFO - PROCESS | 12656 | _x_x_mozilla_page_load_details 06:04:37 INFO - PROCESS | 12656 | |i|pagename|runs| 06:04:37 INFO - PROCESS | 12656 | |0;dojo.html;3041.8326992015436;3053.084074777567;3065.4175706615756;3095.2261934516373;3106.735927446167;5186.948130518694;5206.114960456722;5293;5371.5165635092835;5432.0493913565115;5245.8950820983655;5249.4750524947485;5344.22508736233;5361.115415956372;5426.887488064947;4658.231391820345;4723.811047558093;4729.4797572267025;4741.170295198343;4911.263310503436;4834.033193361324;4839.588634966032;4870.878228044292;4887.364642596457;4906.264060390952;4683.367745354385;4730.361401210827;4761.476237613877;4771.665983381165;4798.304245884362;4763.7141771493825;4787.928181077286;4801;4837.2260438329695;4888.926666100011;4812.687175333627;4828.03439312139;4831.685940413862;4832.758362081881;4834.154023045988;5146.794128234867;5252.763625636855;5325.387580428262;5387.488188622075;5389.703566303851;5712.171735098429;5719.28508936383;5721.0846264597885;5737.340205876294;5771.307443106854;5550.888982220374;5761.9135712964335;5799.072148456226;5802.390748971382;5808.82573522795;5320.255164277004;5370.355557333106;5371.704556249405;5436.673799571999;5451.291332126877;5937.52499800015;5955.37468565803;5955.880882382415;5956;5956.2852457704485;5365.678059316453;5582.441755824385;5677.574181936371;5678.630888992285;5721.656700597978;5692.231548740867;5779.190913272145;5781.826545203567;5783.59514833966;5786.045302525036;5671.035923892948;5683.005474042109;5693;5699.886002279931;5700.828975130753;5433.782648694087;5626.662400255997;5667.121596152603;5670.461306175906;5676.971615141898;4380.759058251827;4400;4401.867943961686;4441.333799929972;4442.355858400579;3871.4386413969814;3884.2425726982965;3894.844206231776;3899;3927.3127202739406;5303.4696530346655;5322.54758345545;5376.354837419534;5436.700981446058;5461.563074954073;5723.627964182315;5738.741756620962;5749.367569567428;5761.711914404347;5802.1296805479515;5423.2136340230445;5426.294581704353;5433.1850222466155;5447.373552041487;5455.345358556999;4790.305405716222;4901.730404827768;4941.25881117828;4947.851564453073;4960.355153829979;5903.0555111182;5915.497182739516;5917.6745279010065;5921.644701317935;5923.022701254331;5583.553315734813;5715.885682286248;5752.367239603556;5754;5755.079187330007;5796.62321949072;5857.502112320501;5859.56053296013;5889.469947704727;5893.70531473416;5604.551635869202;5616.943830561642;5620.325560932714;5621.747021383966;5621.831345059655;5557.749901254534;5659.151127330933;5682.460166284196;5717.42825717425;5726.255586773693;5608.46719561641;5627.662340259598;5664.886702266014;5666.206731057738;5715.228444159985;5585.553155747449;5590.217369568182;5600.27196464457;5615.831525054255;5616.943830561642;5335.06636338647;5500.064988951808;5504.972475137599;5526.392096869422;5549.195366671891;5674.035413979551;5724.742386592697;5726.341470730919;5727.599068065196;5729.1119876419225;5566.332040155207;5567.331920169444;5570.777168913198;5572.86067848307;5578.665280083208 06:04:37 INFO - PROCESS | 12656 | |1;ext.html;603.4659326496053;620.953428492863;622.4366947912141;627.7175271127993;647.1360733420883;33351.33297334055;43290.350644740356;44668.77665611675;44671.329930051;44810.103797924065;45373.319400208944;45729;45854.541454585444;45952.3107153393;45952.770236148855;8945.776355591113;9079.636814527445;9088.636454541811;9089.909100908972;9097.226735727438;8953.731388058337;9039.276857851357;9041.276697864156;9043.276537876955;9044;9072.546372681372;9104.180623743861;9105.68130115446;9110.726678199664;9117.088291170863;9056.637734490612;9070.229030532413;9095.454272743624;9102.317326200528;9103.317251206186;8890.644374225023;8971.730848074569;8976.326775491863;8977.640894364218;9000.189982901571;43306.35040474396;43535.78232108819;43716.12567748659;43743.34384984228;43745.78127109376;3609.2240168363664;3670.5228320318456;3705.870294539678;3802.1825307559006;4104.261232978063;32744.181395465064;33456.330873382394;33552.32895342104;33675.15812104717;33696;4423.601875831158;4454.420925279725;4458.643308535342;4501.414816073922;4506;346378;347660;348365;348539;350472;58164.70917645385;58799;59047;59093.93171238986;59100.70449647724;58533;59161.70419147877;60867.69566152141;60994.69502652458;66548.66725666341;12974.675633109058;12974.870251297554;12989.090763646644;13020.28388438645;13041.413136408884;9753.951230243945;11110.61112861046;11120.61077862271;11132.49903754333;11151.832722509169;16461.917690411472;16773.58066048334;16955.830441695423;17287.74068388975;17369.2183851727;6002.579819412599;6008.279006519158;6038.456538911519;6074.119252708332;6082.905077086167;983.9360441571275;1043.3687618990552;1124.9100071994385;1135.250734515216;1138.703936976392;32587.980562955658;33089.66910330914;33523.329533409196;33527.49708754371;33996.830015849766;1077.5582011375297;1114.5207560748793;1129.108004676313;1164.2606944590077;1253.6301790971686;17066.23201955915;17598.136726676606;17608.207630656394;17638.647227055644;17676.734848977274;580831;584979;585432;590097;591093;58284.70857645769;58532;58779;58822.70588647029;58859.411405885396;1212.2968678166642;1323.4772264955288;1346.2326473909973;1347.7708789505807;1351.216294549159;29508.409831803674;34159.31681366309;34202;34430.65569344274;34777.65222347744;50991.74504127455;51527;51603.74198128985;51652.48347516627;51725;426483;430587;431046;431056;432490;41380;42253;42774.358384623636;42981.355279670206;43056.35415468833;49118.26322605235;49465.75267123641;49496.75251623719;50245.74877125591;50662.746686266335;42943;44498;44852;45879.77060114678;46163.229596300465;41802.37296440495;41827;41930.161396772506;41988.1602367957;42648.57351426446;41814;42771.7861410691;43063.13873722571;43137.56862431335;43520.56479435165;36859.815700921325;37720.811395942845;38335.42496862605;38347.2330553393;38576;28803;28979.851242787787;29130.417391651623;29180.562291566068;29319.973800916865 06:04:37 INFO - PROCESS | 12656 | |2;jquery.html;370.2077554034365;392.4505692031157;395.2056366702928;401.1695789715289;425.38319436816624;14654.926725366371;18661;19812.40562783115;20464.283750068767;20467.48831279219;11487.712807179803;11926.165168438212;12017.699557511045;12049.15655904087;12080.939595302034;4005.83976640934;5281.86795330116;5412.566994640439;5412.648177868426;5413.702246376448;5780.132980052984;6047.5766696331275;6058.485028772538;6156.415140561662;6158.137860699506;5839.532837373021;6045.0327947528185;6069.726862291208;6072.6356418614805;6112.083187521885;6120.571559990803;6123.479504242123;6129.049997250433;6134.171886795315;6168.75324987;5762.9135562966585;5772.913406298909;5835.066389377679;5841.591088623798;5850.6489610623075;13397.933010334886;13457.86542134576;13498.392572334269;13636.454541818315;13638.727225455534;2400.111958575321;2617.4895895300424;2920.73713365796;3057.8623961921767;3096.3962027404664;10339.63811266602;10512.526936287886;10834.512446939907;11227.326360418401;11274;1960.598077394145;1990.2536548794203;1994.1425187169511;2051.0872661665567;2077.55332603491;59475.40524594742;68730.65634671795;80039.5998020012;80294.598527007;89202.55398723029;11093.223474356879;11171.608993685182;11189.160812939062;11218.270812397168;11537.653870383901;15866.603334916488;15891.920540397225;15972.680546389007;16041.59896002586;16052.678946421007;7494.775156745306;7883.6058197089915;7901.209879012053;7917.960410197912;7924.801879953047;7404.1855395906405;7454.627268636546;7478.813029674193;7589.392848572212;7617.695292188361;4563.748993805372;4665.95334046662;4835.661503694708;4899.167141585939;4901.950980490149;1938.5928954919343;1956.6771482705194;2020.3331602285136;2030.3401394546831;2047.0174316327948;878.3280790195505;992.9652462163934;1003.242551873331;1023.4729114506035;1054.2514814481651;20177;20721.792782071985;21193.576128477653;21342.14631414726;21915.67126493104;1000.2048371544494;1001.2020360413056;1030.0935177044303;1046.6232156423682;1051.8790339111101;11210.439478026066;11715.297082175097;11777.52889884395;11816.291022538675;11826;518939;539488;539764;540333;550414;88450.55774721214;102710.75603195748;103935;105082.47458762811;108837;983.1593987141034;1015.5582321690089;1021.5403068619152;1035.5495359518484;1042.5621239079605;19394.321198757974;19422.8057719421;19750;19878.70181947243;19894.403167904988;45508.54491455043;46573.53426465827;46701.100381765646;47568.286475703586;47629.28556071731;60908.69545652244;63473;64981;66206.33793662002;67381.66309168423;7673.309402153833;8049.27556519916;8703.69537066225;8929.330300227379;9135.451872887648;13935.442582296595;14030.088044277089;14089.15465072099;14133.293335332986;14248.857511424752;11569.768604628029;11795;11971.341576213392;12083.57707480234;12119.394030298274;11981.460834262305;12220.938895305466;12231.510739570318;12416.689582760504;12608.243505389706;12368.134230603771;12465.750684986431;12553.121281510206;12741.299228542519;13006.089573730127;8080.4343695940715;8101.946746923098;8116.431849770459;8312.21034001769;8325.375596830141;9344.112309330603;11089.22375433712;11691.824622630838;11737.765244695229;12061.276323420623 06:04:37 INFO - PROCESS | 12656 | |3;mootools.html;1445.9421623135072;1509.5395904249203;1609.2114863716774;1782.2959930827315;1782.6880295948215;8609.569521523923;9048.095190480948;9131.543422828849;9136.588853501591;9144.634214631415;7607.847843043136;7817.960910195456;7821.530708157515;8013.519188848673;8914.50970196639;2250.077468238019;2321.0599707118645;2322.175627652176;2327.138958585326;2333.94165145871;2332.2769941318247;2346.683197768305;2361.622140457527;2368.6447032945025;2374.1097088591778;2343.847649902751;2351.6590094436297;2357.210334537932;2361.4686695493547;2379.524095180962;2318.2929206591984;2319.512902290504;2330.7203135623663;2335.5095429959642;2337.275444612167;2300.4708916949194;2302.4013756423283;2305.8385912986096;2310.2838120182796;2312.28319221042;9069.455832650063;9113.134252246025;9119.863202051974;9128.634854605807;9129.22401595865;5150.562202212816;5295.4969277918535;5301.284326615934;5302.734863256822;5309.070912590281;3278.508223766454;3326.9833650831833;3449.6205417404067;3450.3099380124077;3454.1709989602564;1162.5698491558178;1183.8283448899947;1196.3958201108385;1197.7245233596323;1209.4799236328463;12560.874391256153;12587.496500139983;12588.937055314756;12604.369781510979;12607.747845043139;4436.600705936481;4501.48232953208;4655.930161047587;4656.837010704677;4658.138244424792;10586.682399528027;10608.787824243473;10623.36259824413;10626.36241825493;10631.362118272928;5255.658382205144;5276.261323414725;5280.6039547034125;5297.125974214211;5297.576193904479;4230.619244267969;4269.786510674454;4794.208955522369;4847.103285892122;4854.368932038812;4007.7595344279434;4011.5186177658866;4079.7144199906315;4093.099518106012;4104.958950410458;1261.4323554400557;1278.7122897348022;1301.8372703412074;1303.419978109732;1305.09948135786;1022.7238645565805;1026.7330494071596;1039.1634734039171;1040.47976011994;1056.1920131099714;6260.624362538262;6263.4362907338555;6351.4283714465;6411.4229719325485;6436.388543088399;461.87529367070704;474.23647926837765;474.38093288259154;476.09066682636;481.30211193769173;4283.378910058024;4338.1323735252445;4361.9127617447475;4376.759278239728;4456.509783923766;10923.071538919123;11063.889361106287;11219.719507012227;11254.718632034264;11357.602483913024;11098.944505277583;11101.944490277498;11105.722356941142;11109.888901110886;11199.72000699989;7903.604819758989;8188.01743790737;8245.464044837066;8254.669813207525;8467.026291976379;6169.259688837368;6185.907211391923;6349.555531112777;6402.423781859655;6421.8715625688155;10370.27408081427;10521.947390263;10571.207159463225;10656.094231990368;10784.352938823695;9283.535823208947;10192.490375481344;10806.18953578469;10943.507542160782;10943.945280273547;7782.338501227459;7892.526448413114;7979.16218797018;7989.001374828146;7989.600519973862;8419.789505262417;8486.278666313432;8521.147885211429;8523.872141917753;8536.871946920677;7481.513701609378;7764.495307804975;7769.844603107793;7830.138684744559;7879.645415956181;7577.772666820004;7632.771016869503;7656.119546252252;7670.7698769037015;7706.267904549283;6770.458363330823;6775.830604234933;6816.147981502312;6824.829379265558;6826.044353790573;4935.827246046371;5163.690178589297;5182.222666600041;5295.25866378715;5355.116405793001;5072.54347108762;5148.536631703165;5178.326817513699;5207.479252074762;5219.8434046978655 06:04:37 INFO - PROCESS | 12656 | |4;prototype.html;328.382640635605;333.2435371706227;336.38945314254613;338.66472192529403;339.9065257054311;5311.867203319919;5336.8132115375965;5338.225957236204;5346.89306213876;5393.541548968343;5281.551068159211;5291.708956007418;5308.203769434586;5312.4687531246855;5324.387695415028;3940.6847452203897;4018.6584140348104;4124.546299907008;4125.711200215986;4143.1713657268665;4057.1337184894983;4103.384492326145;4114.773687447204;4115.753054816721;4133.140086960864;3957.149212919222;4103.589641035903;4110.588941105895;4120.876373708778;4122.113745544707;3999.6600288975474;4100.384942258685;4107.342825147962;4115.794210289474;4121.773302468363;3651.707863370924;3655.5430571178604;3660.890173294805;3662.230931504387;3662.633736626343;5138.409082955471;5188.9881473112755;5221;5232.790688372461;5239.423663397023;4474.38921988084;4696.107739529512;4717.481077081518;4732.532914796389;4735.313379559944;5494.478024587658;5503.944960550383;5511;5514.779408823642;5524.364698059734;3841.1741475582885;3963.2667956427863;3983.462232598591;3995.9400608990886;4005.8798236052967;5719.8570035749435;5734.569907256972;5737.627054241461;5750.827475175752;5761.452661997104;4047.838086476567;4108.007616306089;4117.5176936302905;4117.547069822317;4123.134141830249;3936.193080418507;4167.499900012018;4169.478140478764;4172.833086676499;4197.286461301586;4682.484926658065;4696.201645720235;4709.529047095263;4719.528047195253;4770.189067858403;5545.584081193927;5547.362053363834;5558.221848941152;5562.499375056264;5565.41563135873;1358.531306699187;1417.5109587192403;1428.8785453236383;1435.8635929586674;1436.1526699247495;912.493566070837;914.2777206007313;949.8290307744672;975.453745902297;982.6560703753772;5290.391604965402;5311.892542862392;5318.946810531923;5337.466253374709;5337.519623233928;5604.215409842625;5613.691246981455;5642.435756424325;5672.376038635747;5707.315122185364;5146.562542183881;5345.049057094133;5350.812721554805;5362.276092727432;5374.35507739066;2997.4754417976765;3005.1285127312835;3006.5189569669187;3013.2015016020773;3015.894443694504;5624.521915637135;5796.275465566804;5825.533957283407;5837.970810146007;5838.416158384128;5849.970750146222;5898.941010589925;5899.970500147472;5907.55264960079;5909.23179986599;5915.935131676253;5925.614835035709;5953.821385358447;5962.07587823888;5984.2818861736;5553.194786755817;5733.541316694571;5734.397888221677;5734.799282025109;5776.624519406225;5586.776528938797;5660.867826434812;5670.376258611467;5690.5163061140265;5691.800786972436;5567.805126820542;5886.411358864079;5897.64614123154;5905.527557795452;5927.911081333697;4061.735987160825;4076.3885417187657;4099.651529620016;4103.302438585507;4121.9587804121575;5737.196792449144;5748.109043098327;5750.597458178055;5771.509421699203;5784.132380143013;5341.599380046434;5605.803796867253;5662.348829884616;5671.829845104653;5676.347220069745;5538.944610554004;5556.16657501378;5558.749856256397;5574.721263936706;5605.747741351731;5350.571954243729;5461.453854614507;5476.17857321405;5544.805931792368;5552.472515111058;5157.303763991963;5538;5571.523546260325;5618.101103823532;5621.409751976147;5480.712032672399;5484.972575137099;5488.945110549003;5514.889702205854;5516.3380394352935 06:04:37 INFO - PROCESS | 12656 | |5;yui.html;792.3819420851735;800.2317774936062;801.6232370785729;830.3813658824176;870.5603670146575;13642.249676267802;14295;14297.428102875872;14321.069130506523;14325.355359008843;14491;14712;14722.926385368084;14734;14742.115473071623;656.2518728649344;675.3887731602907;679.2731776998613;680.7345135397175;787.051602818605;798.9480683755567;803.441608082383;804.493169303338;804.7143264141233;804.7706403674954;844.8901642786429;849.9915000850006;852.6291063387446;854.150120629974;854.9444286121413;849.9490030598184;850.6427300533791;850.8043150075456;850.9191626795445;852.1819053708448;508.379776672459;605.966671833049;621.5121129912997;622.0731110645106;627.0562802981484;14837.035592686549;14839.851601483955;14856.331465084097;14894.478693245685;14931.925340373336;13825.79261311081;14208.218547979857;14273.35769890347;14323;14349.13905165683;13501.459941602325;13507.86492135076;13648.181109133484;13694.109882857583;13939;4566.1780879441685;4573.611243044345;4622.930656040162;4780.952190478086;4788.569028787426;77314.61342693307;78137.13971452163;78665.60667196685;79411.60294198492;80114;12085.516579336903;12109.576164834365;12141.575044873387;12435.191712538655;12734.872651273368;22655.320340389815;22694.546109077724;22738.09047638109;22763.430914226945;22826.65760013601;8654.264387527131;8714.738557843275;8723.47659140441;8744.737657870273;9121.270298376114;4400.207962566768;4609.078184363141;4626.768661566908;4732.02993386355;4752.7861246244;3537.20412907099;3676.117731744362;3684.373656478405;3689.317476266899;3694.07648087978;620.4818976154871;626.6553395632475;629.2071989293431;630.1115427247559;647.6793986976382;703.6503718658663;703.6903762344552;706.9929300706928;740.3854800515559;759.1194214710908;10014.198864090855;10041.19670426364;10123.696289111338;10212.957408518327;10410.375377477376;679.3681875855403;686.1457485430572;714.6248219684708;720.0399582614147;721.7834649605097;4397.626201772821;4537.909241815211;4576.519465456146;4578.404807375086;4595.816167353335;114907;117427;118288.40855795666;118689;119763.40118299353;102410.48794756127;103692;104998;105080;105261;58385.41614583885;59272;88406.53956916838;89150;90507.54746226227;8867.822643547222;8945.59744811498;8956.865647015169;9051.094890510896;9239.861402079108;52434;53812;54616.453835461136;55258.44741552533;56531.15203272036;47084;62829;77149.61425192837;77352.2264777345;77719.83420248587;11831.230969986924;11836.76326473483;11883.346415947206;11896.107791915814;12328.260304381765;12856.42146103409;12881.935590321988;13124.868751312364;13209.207447553177;13617.931910340385;11777.587784427504;11834;12151.635450936486;12380.566680166152;12842.550510732079;10990.835137472784;11098.223124381217;11400.144989126014;11806.409679515818;12322.938385308016;12060;12060.216085954386;12146.696332591757;12371.938140309241;12592.433340499516;9443.622255109993;9497.667581634609;9609.183219426428;9718.319717619699;9953.800923981335;9745.853812192965;9991.900080999098;10001.699949001542;10833.133349332193;11124.276922000045 06:04:37 INFO - PROCESS | 12656 | __end_tp_report 06:04:37 INFO - PROCESS | 12656 | __start_cc_report 06:04:37 INFO - PROCESS | 12656 | _x_x_mozilla_cycle_collect,886 06:04:37 INFO - PROCESS | 12656 | __end_cc_report 06:04:37 INFO - PROCESS | 12656 | __startTimestamp1468847077587__endTimestamp 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | ------- Summary: start ------- 06:04:37 INFO - PROCESS | 12656 | Number of tests: 6 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | [#0] dojo.html Cycles:165 Average:5305.89 Median:5550.89 stddev:615.57 (11.1%) stddev-sans-first:591.28 06:04:37 INFO - PROCESS | 12656 | Values: 3041.8 3053.1 3065.4 3095.2 3106.7 5186.9 5206.1 5293.0 5371.5 5432.0 5245.9 5249.5 5344.2 5361.1 5426.9 4658.2 4723.8 4729.5 4741.2 4911.3 4834.0 4839.6 4870.9 4887.4 4906.3 4683.4 4730.4 4761.5 4771.7 4798.3 4763.7 4787.9 4801.0 4837.2 4888.9 4812.7 4828.0 4831.7 4832.8 4834.2 5146.8 5252.8 5325.4 5387.5 5389.7 5712.2 5719.3 5721.1 5737.3 5771.3 5550.9 5761.9 5799.1 5802.4 5808.8 5320.3 5370.4 5371.7 5436.7 5451.3 5937.5 5955.4 5955.9 5956.0 5956.3 5365.7 5582.4 5677.6 5678.6 5721.7 5692.2 5779.2 5781.8 5783.6 5786.0 5671.0 5683.0 5693.0 5699.9 5700.8 5433.8 5626.7 5667.1 5670.5 5677.0 4380.8 4400.0 4401.9 4441.3 4442.4 3871.4 3884.2 3894.8 3899.0 3927.3 5303.5 5322.5 5376.4 5436.7 5461.6 5723.6 5738.7 5749.4 5761.7 5802.1 5423.2 5426.3 5433.2 5447.4 5455.3 4790.3 4901.7 4941.3 4947.9 4960.4 5903.1 5915.5 5917.7 5921.6 5923.0 5583.6 5715.9 5752.4 5754.0 5755.1 5796.6 5857.5 5859.6 5889.5 5893.7 5604.6 5616.9 5620.3 5621.7 5621.8 5557.7 5659.2 5682.5 5717.4 5726.3 5608.5 5627.7 5664.9 5666.2 5715.2 5585.6 5590.2 5600.3 5615.8 5616.9 5335.1 5500.1 5505.0 5526.4 5549.2 5674.0 5724.7 5726.3 5727.6 5729.1 5566.3 5567.3 5570.8 5572.9 5578.7 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | [#1] ext.html Cycles:180 Average:62171.92 Median:43413.46 stddev:123647.46 (284.8%) stddev-sans-first:123907.92 06:04:37 INFO - PROCESS | 12656 | Values: 603.5 621.0 622.4 627.7 647.1 33351.3 43290.4 44668.8 44671.3 44810.1 45373.3 45729.0 45854.5 45952.3 45952.8 8945.8 9079.6 9088.6 9089.9 9097.2 8953.7 9039.3 9041.3 9043.3 9044.0 9072.5 9104.2 9105.7 9110.7 9117.1 9056.6 9070.2 9095.5 9102.3 9103.3 8890.6 8971.7 8976.3 8977.6 9000.2 43306.4 43535.8 43716.1 43743.3 43745.8 3609.2 3670.5 3705.9 3802.2 4104.3 32744.2 33456.3 33552.3 33675.2 33696.0 4423.6 4454.4 4458.6 4501.4 4506.0 346378.0 347660.0 348365.0 348539.0 350472.0 58164.7 58799.0 59047.0 59093.9 59100.7 58533.0 59161.7 60867.7 60994.7 66548.7 12974.7 12974.9 12989.1 13020.3 13041.4 9754.0 11110.6 11120.6 11132.5 11151.8 16461.9 16773.6 16955.8 17287.7 17369.2 6002.6 6008.3 6038.5 6074.1 6082.9 983.9 1043.4 1124.9 1135.3 1138.7 32588.0 33089.7 33523.3 33527.5 33996.8 1077.6 1114.5 1129.1 1164.3 1253.6 17066.2 17598.1 17608.2 17638.6 17676.7 580831.0 584979.0 585432.0 590097.0 591093.0 58284.7 58532.0 58779.0 58822.7 58859.4 1212.3 1323.5 1346.2 1347.8 1351.2 29508.4 34159.3 34202.0 34430.7 34777.7 50991.7 51527.0 51603.7 51652.5 51725.0 426483.0 430587.0 431046.0 431056.0 432490.0 41380.0 42253.0 42774.4 42981.4 43056.4 49118.3 49465.8 49496.8 50245.7 50662.7 42943.0 44498.0 44852.0 45879.8 46163.2 41802.4 41827.0 41930.2 41988.2 42648.6 41814.0 42771.8 43063.1 43137.6 43520.6 36859.8 37720.8 38335.4 38347.2 38576.0 28803.0 28979.9 29130.4 29180.6 29320.0 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | [#2] jquery.html Cycles:180 Average:30541.79 Median:11399.67 stddev:88710.74 (778.2%) stddev-sans-first:88930.67 06:04:37 INFO - PROCESS | 12656 | Values: 370.2 392.5 395.2 401.2 425.4 14654.9 18661.0 19812.4 20464.3 20467.5 11487.7 11926.2 12017.7 12049.2 12080.9 4005.8 5281.9 5412.6 5412.6 5413.7 5780.1 6047.6 6058.5 6156.4 6158.1 5839.5 6045.0 6069.7 6072.6 6112.1 6120.6 6123.5 6129.0 6134.2 6168.8 5762.9 5772.9 5835.1 5841.6 5850.6 13397.9 13457.9 13498.4 13636.5 13638.7 2400.1 2617.5 2920.7 3057.9 3096.4 10339.6 10512.5 10834.5 11227.3 11274.0 1960.6 1990.3 1994.1 2051.1 2077.6 59475.4 68730.7 80039.6 80294.6 89202.6 11093.2 11171.6 11189.2 11218.3 11537.7 15866.6 15891.9 15972.7 16041.6 16052.7 7494.8 7883.6 7901.2 7918.0 7924.8 7404.2 7454.6 7478.8 7589.4 7617.7 4563.7 4666.0 4835.7 4899.2 4902.0 1938.6 1956.7 2020.3 2030.3 2047.0 878.3 993.0 1003.2 1023.5 1054.3 20177.0 20721.8 21193.6 21342.1 21915.7 1000.2 1001.2 1030.1 1046.6 1051.9 11210.4 11715.3 11777.5 11816.3 11826.0 518939.0 539488.0 539764.0 540333.0 550414.0 88450.6 102710.8 103935.0 105082.5 108837.0 983.2 1015.6 1021.5 1035.5 1042.6 19394.3 19422.8 19750.0 19878.7 19894.4 45508.5 46573.5 46701.1 47568.3 47629.3 60908.7 63473.0 64981.0 66206.3 67381.7 7673.3 8049.3 8703.7 8929.3 9135.5 13935.4 14030.1 14089.2 14133.3 14248.9 11569.8 11795.0 11971.3 12083.6 12119.4 11981.5 12220.9 12231.5 12416.7 12608.2 12368.1 12465.8 12553.1 12741.3 13006.1 8080.4 8101.9 8116.4 8312.2 8325.4 9344.1 11089.2 11691.8 11737.8 12061.3 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | [#3] mootools.html Cycles:180 Average:5870.26 Median:4446.56 stddev:3382.10 (76.1%) stddev-sans-first:3375.24 06:04:37 INFO - PROCESS | 12656 | Values: 1445.9 1509.5 1609.2 1782.3 1782.7 8609.6 9048.1 9131.5 9136.6 9144.6 7607.8 7818.0 7821.5 8013.5 8914.5 2250.1 2321.1 2322.2 2327.1 2333.9 2332.3 2346.7 2361.6 2368.6 2374.1 2343.8 2351.7 2357.2 2361.5 2379.5 2318.3 2319.5 2330.7 2335.5 2337.3 2300.5 2302.4 2305.8 2310.3 2312.3 9069.5 9113.1 9119.9 9128.6 9129.2 5150.6 5295.5 5301.3 5302.7 5309.1 3278.5 3327.0 3449.6 3450.3 3454.2 1162.6 1183.8 1196.4 1197.7 1209.5 12560.9 12587.5 12588.9 12604.4 12607.7 4436.6 4501.5 4655.9 4656.8 4658.1 10586.7 10608.8 10623.4 10626.4 10631.4 5255.7 5276.3 5280.6 5297.1 5297.6 4230.6 4269.8 4794.2 4847.1 4854.4 4007.8 4011.5 4079.7 4093.1 4105.0 1261.4 1278.7 1301.8 1303.4 1305.1 1022.7 1026.7 1039.2 1040.5 1056.2 6260.6 6263.4 6351.4 6411.4 6436.4 461.9 474.2 474.4 476.1 481.3 4283.4 4338.1 4361.9 4376.8 4456.5 10923.1 11063.9 11219.7 11254.7 11357.6 11098.9 11101.9 11105.7 11109.9 11199.7 7903.6 8188.0 8245.5 8254.7 8467.0 6169.3 6185.9 6349.6 6402.4 6421.9 10370.3 10521.9 10571.2 10656.1 10784.4 9283.5 10192.5 10806.2 10943.5 10943.9 7782.3 7892.5 7979.2 7989.0 7989.6 8419.8 8486.3 8521.1 8523.9 8536.9 7481.5 7764.5 7769.8 7830.1 7879.6 7577.8 7632.8 7656.1 7670.8 7706.3 6770.5 6775.8 6816.1 6824.8 6826.0 4935.8 5163.7 5182.2 5295.3 5355.1 5072.5 5148.5 5178.3 5207.5 5219.8 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | [#4] prototype.html Cycles:180 Average:4681.79 Median:5343.32 stddev:1380.32 (25.8%) stddev-sans-first:1344.96 06:04:37 INFO - PROCESS | 12656 | Values: 328.4 333.2 336.4 338.7 339.9 5311.9 5336.8 5338.2 5346.9 5393.5 5281.6 5291.7 5308.2 5312.5 5324.4 3940.7 4018.7 4124.5 4125.7 4143.2 4057.1 4103.4 4114.8 4115.8 4133.1 3957.1 4103.6 4110.6 4120.9 4122.1 3999.7 4100.4 4107.3 4115.8 4121.8 3651.7 3655.5 3660.9 3662.2 3662.6 5138.4 5189.0 5221.0 5232.8 5239.4 4474.4 4696.1 4717.5 4732.5 4735.3 5494.5 5503.9 5511.0 5514.8 5524.4 3841.2 3963.3 3983.5 3995.9 4005.9 5719.9 5734.6 5737.6 5750.8 5761.5 4047.8 4108.0 4117.5 4117.5 4123.1 3936.2 4167.5 4169.5 4172.8 4197.3 4682.5 4696.2 4709.5 4719.5 4770.2 5545.6 5547.4 5558.2 5562.5 5565.4 1358.5 1417.5 1428.9 1435.9 1436.2 912.5 914.3 949.8 975.5 982.7 5290.4 5311.9 5318.9 5337.5 5337.5 5604.2 5613.7 5642.4 5672.4 5707.3 5146.6 5345.0 5350.8 5362.3 5374.4 2997.5 3005.1 3006.5 3013.2 3015.9 5624.5 5796.3 5825.5 5838.0 5838.4 5850.0 5898.9 5900.0 5907.6 5909.2 5915.9 5925.6 5953.8 5962.1 5984.3 5553.2 5733.5 5734.4 5734.8 5776.6 5586.8 5660.9 5670.4 5690.5 5691.8 5567.8 5886.4 5897.6 5905.5 5927.9 4061.7 4076.4 4099.7 4103.3 4122.0 5737.2 5748.1 5750.6 5771.5 5784.1 5341.6 5605.8 5662.3 5671.8 5676.3 5538.9 5556.2 5558.7 5574.7 5605.7 5350.6 5461.5 5476.2 5544.8 5552.5 5157.3 5538.0 5571.5 5618.1 5621.4 5480.7 5485.0 5488.9 5514.9 5516.3 06:04:37 INFO - PROCESS | 12656 | 06:04:37 INFO - PROCESS | 12656 | [#5] yui.html Cycles:180 Average:20575.59 Median:4602.45 stddev:30215.05 (656.5%) stddev-sans-first:30263.29 06:04:37 INFO - PROCESS | 12656 | Values: 792.4 800.2 801.6 830.4 870.6 13642.2 14295.0 14297.4 14321.1 14325.4 14491.0 14712.0 14722.9 14734.0 14742.1 656.3 675.4 679.3 680.7 787.1 798.9 803.4 804.5 804.7 804.8 844.9 850.0 852.6 854.2 854.9 849.9 850.6 850.8 850.9 852.2 508.4 606.0 621.5 622.1 627.1 14837.0 14839.9 14856.3 14894.5 14931.9 13825.8 14208.2 14273.4 14323.0 14349.1 13501.5 13507.9 13648.2 13694.1 13939.0 4566.2 4573.6 4622.9 4781.0 4788.6 77314.6 78137.1 78665.6 79411.6 80114.0 12085.5 12109.6 12141.6 12435.2 12734.9 22655.3 22694.5 22738.1 22763.4 22826.7 8654.3 8714.7 8723.5 8744.7 9121.3 4400.2 4609.1 4626.8 4732.0 4752.8 3537.2 3676.1 3684.4 3689.3 3694.1 620.5 626.7 629.2 630.1 647.7 703.7 703.7 707.0 740.4 759.1 10014.2 10041.2 10123.7 10213.0 10410.4 679.4 686.1 714.6 720.0 721.8 4397.6 4537.9 4576.5 4578.4 4595.8 114907.0 117427.0 118288.4 118689.0 119763.4 102410.5 103692.0 104998.0 105080.0 105261.0 58385.4 59272.0 88406.5 89150.0 90507.5 8867.8 8945.6 8956.9 9051.1 9239.9 52434.0 53812.0 54616.5 55258.4 56531.2 47084.0 62829.0 77149.6 77352.2 77719.8 11831.2 11836.8 11883.3 11896.1 12328.3 12856.4 12881.9 13124.9 13209.2 13617.9 11777.6 11834.0 12151.6 12380.6 12842.6 10990.8 11098.2 11400.1 11806.4 12322.9 12060.0 12060.2 12146.7 12371.9 12592.4 9443.6 9497.7 9609.2 9718.3 9953.8 9745.9 9991.9 10001.7 10833.1 11124.3 06:04:37 INFO - PROCESS | 12656 | -------- Summary: end -------- 06:04:37 INFO - PROCESS | 12656 | 06:04:38 INFO - TEST-INFO | 12656: exit 0 06:04:38 INFO - TEST-OK | dromaeo_css | took 1112524ms 06:04:38 INFO - TEST-START | kraken 06:04:38 INFO - operating with platform_type : linux_ 06:04:38 INFO - Initialising browser for kraken test... 06:04:38 INFO - TEST-INFO | started process 12850 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuXOPd7/profile http://localhost:58072/getInfo.html) 06:04:41 INFO - PROCESS | 12850 | JavaScript error: , line 0: uncaught exception: undefined 06:04:41 INFO - PROCESS | 12850 | __metrics Screen width/height:1600/1200 06:04:41 INFO - PROCESS | 12850 | colorDepth:24 06:04:41 INFO - PROCESS | 12850 | Browser inner width/height: 1024/697 06:04:41 INFO - PROCESS | 12850 | __metrics 06:04:42 INFO - PROCESS | 12850 | 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" 06:04:42 INFO - PROCESS | 12850 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:04:43 INFO - TEST-INFO | 12850: exit 0 06:04:43 INFO - Browser initialized. 06:04:43 INFO - Running cycle 1/1 for kraken test... 06:04:43 INFO - TEST-INFO | started process 12935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuXOPd7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:55 INFO - PROCESS | 12935 | RSS: Main: 157691904 06:04:55 INFO - PROCESS | 12935 | 06:04:57 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58072/tests/kraken/driver.html?testName=audio-beat-detection) 06:04:58 INFO - PROCESS | 12935 | RSS: Main: 216907776 06:04:58 INFO - PROCESS | 12935 | 06:05:01 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58072/tests/kraken/driver.html?testName=audio-dft) 06:05:01 INFO - PROCESS | 12935 | RSS: Main: 205955072 06:05:01 INFO - PROCESS | 12935 | 06:05:04 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58072/tests/kraken/driver.html?testName=audio-fft) 06:05:05 INFO - PROCESS | 12935 | RSS: Main: 270663680 06:05:05 INFO - PROCESS | 12935 | 06:05:07 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58072/tests/kraken/driver.html?testName=audio-oscillator) 06:05:08 INFO - PROCESS | 12935 | RSS: Main: 180576256 06:05:08 INFO - PROCESS | 12935 | 06:05:10 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58072/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:10 INFO - PROCESS | 12935 | RSS: Main: 185221120 06:05:10 INFO - PROCESS | 12935 | 06:05:13 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58072/tests/kraken/driver.html?testName=imaging-darkroom) 06:05:14 INFO - PROCESS | 12935 | RSS: Main: 219574272 06:05:14 INFO - PROCESS | 12935 | 06:05:17 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58072/tests/kraken/driver.html?testName=imaging-desaturate) 06:05:18 INFO - PROCESS | 12935 | RSS: Main: 221589504 06:05:18 INFO - PROCESS | 12935 | 06:05:21 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58072/tests/kraken/driver.html?testName=json-parse-financial) 06:05:21 INFO - PROCESS | 12935 | RSS: Main: 222035968 06:05:21 INFO - PROCESS | 12935 | 06:05:23 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58072/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:05:23 INFO - PROCESS | 12935 | RSS: Main: 210096128 06:05:23 INFO - PROCESS | 12935 | 06:05:25 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:05:26 INFO - PROCESS | 12935 | RSS: Main: 215076864 06:05:26 INFO - PROCESS | 12935 | 06:05:28 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:05:29 INFO - PROCESS | 12935 | RSS: Main: 211767296 06:05:29 INFO - PROCESS | 12935 | 06:05:31 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:05:32 INFO - PROCESS | 12935 | RSS: Main: 224903168 06:05:32 INFO - PROCESS | 12935 | 06:05:34 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:05:35 INFO - PROCESS | 12935 | RSS: Main: 225312768 06:05:35 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | Cycle 1(1): loaded http://localhost:58072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58072/tests/kraken/driver.html?testName=ai-astar) 06:05:37 INFO - PROCESS | 12935 | RSS: Main: 267706368 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | __start_tp_report 06:05:37 INFO - PROCESS | 12935 | _x_x_mozilla_page_load 06:05:37 INFO - PROCESS | 12935 | _x_x_mozilla_page_load_details 06:05:37 INFO - PROCESS | 12935 | |i|pagename|runs| 06:05:37 INFO - PROCESS | 12935 | |0;ai-astar;101;110;110;110;112;111;110;111;109;110 06:05:37 INFO - PROCESS | 12935 | |1;audio-beat-detection;153;120;119;166;117;117;146;115;148;118 06:05:37 INFO - PROCESS | 12935 | |2;audio-dft;164;161;161;161;161;161;163;161;163;159 06:05:37 INFO - PROCESS | 12935 | |3;audio-fft;75;75;75;131;76;76;74;74;110;75 06:05:37 INFO - PROCESS | 12935 | |4;audio-oscillator;84;83;135;85;84;84;120;84;84;128 06:05:37 INFO - PROCESS | 12935 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 06:05:37 INFO - PROCESS | 12935 | |6;imaging-darkroom;149;149;148;149;148;150;149;149;149;145 06:05:37 INFO - PROCESS | 12935 | |7;imaging-desaturate;96;95;95;96;95;95;96;97;95;95 06:05:37 INFO - PROCESS | 12935 | |8;json-parse-financial;50;51;49;49;50;50;49;50;50;50 06:05:37 INFO - PROCESS | 12935 | |9;json-stringify-tinderbox;62;62;62;62;62;62;63;61;63;62 06:05:37 INFO - PROCESS | 12935 | |10;stanford-crypto-aes;76;84;75;75;75;76;75;76;74;74 06:05:37 INFO - PROCESS | 12935 | |11;stanford-crypto-ccm;126;117;117;118;129;133;123;132;160;128 06:05:37 INFO - PROCESS | 12935 | |12;stanford-crypto-pbkdf2;159;158;159;157;156;153;158;162;157;155 06:05:37 INFO - PROCESS | 12935 | |13;stanford-crypto-sha256-iterative;63;61;62;63;70;62;64;65;60;61 06:05:37 INFO - PROCESS | 12935 | __end_tp_report 06:05:37 INFO - PROCESS | 12935 | __start_cc_report 06:05:37 INFO - PROCESS | 12935 | _x_x_mozilla_cycle_collect,1633 06:05:37 INFO - PROCESS | 12935 | __end_cc_report 06:05:37 INFO - PROCESS | 12935 | __startTimestamp1468847137302__endTimestamp 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | ------- Summary: start ------- 06:05:37 INFO - PROCESS | 12935 | Number of tests: 14 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:3.06 (2.8%) stddev-sans-first:0.87 06:05:37 INFO - PROCESS | 12935 | Values: 101.0 110.0 110.0 110.0 112.0 111.0 110.0 111.0 109.0 110.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:133.00 stddev:19.14 (14.4%) stddev-sans-first:18.72 06:05:37 INFO - PROCESS | 12935 | Values: 153.0 120.0 119.0 166.0 117.0 117.0 146.0 115.0 148.0 118.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.00 stddev:1.43 (0.9%) stddev-sans-first:1.20 06:05:37 INFO - PROCESS | 12935 | Values: 164.0 161.0 161.0 161.0 161.0 161.0 163.0 161.0 163.0 159.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#3] audio-fft Cycles:10 Average:84.10 Median:75.00 stddev:19.82 (26.4%) stddev-sans-first:20.75 06:05:37 INFO - PROCESS | 12935 | Values: 75.0 75.0 75.0 131.0 76.0 76.0 74.0 74.0 110.0 75.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:84.00 stddev:21.39 (25.5%) stddev-sans-first:22.16 06:05:37 INFO - PROCESS | 12935 | Values: 84.0 83.0 135.0 85.0 84.0 84.0 120.0 84.0 84.0 128.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 06:05:37 INFO - PROCESS | 12935 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#6] imaging-darkroom Cycles:10 Average:148.50 Median:149.00 stddev:1.35 (0.9%) stddev-sans-first:1.42 06:05:37 INFO - PROCESS | 12935 | Values: 149.0 149.0 148.0 149.0 148.0 150.0 149.0 149.0 149.0 145.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 06:05:37 INFO - PROCESS | 12935 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 96.0 97.0 95.0 95.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.00 stddev:0.63 (1.3%) stddev-sans-first:0.67 06:05:37 INFO - PROCESS | 12935 | Values: 50.0 51.0 49.0 49.0 50.0 50.0 49.0 50.0 50.0 50.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 06:05:37 INFO - PROCESS | 12935 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 63.0 61.0 63.0 62.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:75.50 stddev:2.91 (3.8%) stddev-sans-first:3.08 06:05:37 INFO - PROCESS | 12935 | Values: 76.0 84.0 75.0 75.0 75.0 76.0 75.0 76.0 74.0 74.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:128.50 stddev:12.63 (9.8%) stddev-sans-first:13.37 06:05:37 INFO - PROCESS | 12935 | Values: 126.0 117.0 117.0 118.0 129.0 133.0 123.0 132.0 160.0 128.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.40 Median:158.00 stddev:2.46 (1.6%) stddev-sans-first:2.54 06:05:37 INFO - PROCESS | 12935 | Values: 159.0 158.0 159.0 157.0 156.0 153.0 158.0 162.0 157.0 155.0 06:05:37 INFO - PROCESS | 12935 | 06:05:37 INFO - PROCESS | 12935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:2.85 (4.5%) stddev-sans-first:3.02 06:05:37 INFO - PROCESS | 12935 | Values: 63.0 61.0 62.0 63.0 70.0 62.0 64.0 65.0 60.0 61.0 06:05:37 INFO - PROCESS | 12935 | -------- Summary: end -------- 06:05:37 INFO - PROCESS | 12935 | 06:05:38 INFO - TEST-INFO | 12935: exit 0 06:05:38 INFO - TEST-OK | kraken | took 59999ms 06:05:38 INFO - SUITE-END | took 1172s 06:05:38 INFO - Completed test suite (00:19:32) 06:05:38 INFO - javascript benchmark 06:05:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3041.8326992015436, 3053.084074777567, 3065.4175706615756, 3095.2261934516373, 3106.735927446167, 5186.948130518694, 5206.114960456722, 5293.0, 5371.5165635092835, 5432.0493913565115, 5245.8950820983655, 5249.4750524947485, 5344.22508736233, 5361.115415956372, 5426.887488064947, 4658.231391820345, 4723.811047558093, 4729.4797572267025, 4741.170295198343, 4911.263310503436, 4834.033193361324, 4839.588634966032, 4870.878228044292, 4887.364642596457, 4906.264060390952, 4683.367745354385, 4730.361401210827, 4761.476237613877, 4771.665983381165, 4798.304245884362, 4763.7141771493825, 4787.928181077286, 4801.0, 4837.2260438329695, 4888.926666100011, 4812.687175333627, 4828.03439312139, 4831.685940413862, 4832.758362081881, 4834.154023045988, 5146.794128234867, 5252.763625636855, 5325.387580428262, 5387.488188622075, 5389.703566303851, 5712.171735098429, 5719.28508936383, 5721.0846264597885, 5737.340205876294, 5771.307443106854, 5550.888982220374, 5761.9135712964335, 5799.072148456226, 5802.390748971382, 5808.82573522795, 5320.255164277004, 5370.355557333106, 5371.704556249405, 5436.673799571999, 5451.291332126877, 5937.52499800015, 5955.37468565803, 5955.880882382415, 5956.0, 5956.2852457704485, 5365.678059316453, 5582.441755824385, 5677.574181936371, 5678.630888992285, 5721.656700597978, 5692.231548740867, 5779.190913272145, 5781.826545203567, 5783.59514833966, 5786.045302525036, 5671.035923892948, 5683.005474042109, 5693.0, 5699.886002279931, 5700.828975130753, 5433.782648694087, 5626.662400255997, 5667.121596152603, 5670.461306175906, 5676.971615141898, 4380.759058251827, 4400.0, 4401.867943961686, 4441.333799929972, 4442.355858400579, 3871.4386413969814, 3884.2425726982965, 3894.844206231776, 3899.0, 3927.3127202739406, 5303.4696530346655, 5322.54758345545, 5376.354837419534, 5436.700981446058, 5461.563074954073, 5723.627964182315, 5738.741756620962, 5749.367569567428, 5761.711914404347, 5802.1296805479515, 5423.2136340230445, 5426.294581704353, 5433.1850222466155, 5447.373552041487, 5455.345358556999, 4790.305405716222, 4901.730404827768, 4941.25881117828, 4947.851564453073, 4960.355153829979, 5903.0555111182, 5915.497182739516, 5917.6745279010065, 5921.644701317935, 5923.022701254331, 5583.553315734813, 5715.885682286248, 5752.367239603556, 5754.0, 5755.079187330007, 5796.62321949072, 5857.502112320501, 5859.56053296013, 5889.469947704727, 5893.70531473416, 5604.551635869202, 5616.943830561642, 5620.325560932714, 5621.747021383966, 5621.831345059655, 5557.749901254534, 5659.151127330933, 5682.460166284196, 5717.42825717425, 5726.255586773693, 5608.46719561641, 5627.662340259598, 5664.886702266014, 5666.206731057738, 5715.228444159985, 5585.553155747449, 5590.217369568182, 5600.27196464457, 5615.831525054255, 5616.943830561642, 5335.06636338647, 5500.064988951808, 5504.972475137599, 5526.392096869422, 5549.195366671891, 5674.035413979551, 5724.742386592697, 5726.341470730919, 5727.599068065196, 5729.1119876419225, 5566.332040155207, 5567.331920169444, 5570.777168913198, 5572.86067848307, 5578.665280083208], "value": 5263.597629700293, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.4659326496053, 620.953428492863, 622.4366947912141, 627.7175271127993, 647.1360733420883, 33351.33297334055, 43290.350644740356, 44668.77665611675, 44671.329930051, 44810.103797924065, 45373.319400208944, 45729.0, 45854.541454585444, 45952.3107153393, 45952.770236148855, 8945.776355591113, 9079.636814527445, 9088.636454541811, 9089.909100908972, 9097.226735727438, 8953.731388058337, 9039.276857851357, 9041.276697864156, 9043.276537876955, 9044.0, 9072.546372681372, 9104.180623743861, 9105.68130115446, 9110.726678199664, 9117.088291170863, 9056.637734490612, 9070.229030532413, 9095.454272743624, 9102.317326200528, 9103.317251206186, 8890.644374225023, 8971.730848074569, 8976.326775491863, 8977.640894364218, 9000.189982901571, 43306.35040474396, 43535.78232108819, 43716.12567748659, 43743.34384984228, 43745.78127109376, 3609.2240168363664, 3670.5228320318456, 3705.870294539678, 3802.1825307559006, 4104.261232978063, 32744.181395465064, 33456.330873382394, 33552.32895342104, 33675.15812104717, 33696.0, 4423.601875831158, 4454.420925279725, 4458.643308535342, 4501.414816073922, 4506.0, 346378.0, 347660.0, 348365.0, 348539.0, 350472.0, 58164.70917645385, 58799.0, 59047.0, 59093.93171238986, 59100.70449647724, 58533.0, 59161.70419147877, 60867.69566152141, 60994.69502652458, 66548.66725666341, 12974.675633109058, 12974.870251297554, 12989.090763646644, 13020.28388438645, 13041.413136408884, 9753.951230243945, 11110.61112861046, 11120.61077862271, 11132.49903754333, 11151.832722509169, 16461.917690411472, 16773.58066048334, 16955.830441695423, 17287.74068388975, 17369.2183851727, 6002.579819412599, 6008.279006519158, 6038.456538911519, 6074.119252708332, 6082.905077086167, 983.9360441571275, 1043.3687618990552, 1124.9100071994385, 1135.250734515216, 1138.703936976392, 32587.980562955658, 33089.66910330914, 33523.329533409196, 33527.49708754371, 33996.830015849766, 1077.5582011375297, 1114.5207560748793, 1129.108004676313, 1164.2606944590077, 1253.6301790971686, 17066.23201955915, 17598.136726676606, 17608.207630656394, 17638.647227055644, 17676.734848977274, 580831.0, 584979.0, 585432.0, 590097.0, 591093.0, 58284.70857645769, 58532.0, 58779.0, 58822.70588647029, 58859.411405885396, 1212.2968678166642, 1323.4772264955288, 1346.2326473909973, 1347.7708789505807, 1351.216294549159, 29508.409831803674, 34159.31681366309, 34202.0, 34430.65569344274, 34777.65222347744, 50991.74504127455, 51527.0, 51603.74198128985, 51652.48347516627, 51725.0, 426483.0, 430587.0, 431046.0, 431056.0, 432490.0, 41380.0, 42253.0, 42774.358384623636, 42981.355279670206, 43056.35415468833, 49118.26322605235, 49465.75267123641, 49496.75251623719, 50245.74877125591, 50662.746686266335, 42943.0, 44498.0, 44852.0, 45879.77060114678, 46163.229596300465, 41802.37296440495, 41827.0, 41930.161396772506, 41988.1602367957, 42648.57351426446, 41814.0, 42771.7861410691, 43063.13873722571, 43137.56862431335, 43520.56479435165, 36859.815700921325, 37720.811395942845, 38335.42496862605, 38347.2330553393, 38576.0, 28803.0, 28979.851242787787, 29130.417391651623, 29180.562291566068, 29319.973800916865], "value": 20441.97917729939, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.2077554034365, 392.4505692031157, 395.2056366702928, 401.1695789715289, 425.38319436816624, 14654.926725366371, 18661.0, 19812.40562783115, 20464.283750068767, 20467.48831279219, 11487.712807179803, 11926.165168438212, 12017.699557511045, 12049.15655904087, 12080.939595302034, 4005.83976640934, 5281.86795330116, 5412.566994640439, 5412.648177868426, 5413.702246376448, 5780.132980052984, 6047.5766696331275, 6058.485028772538, 6156.415140561662, 6158.137860699506, 5839.532837373021, 6045.0327947528185, 6069.726862291208, 6072.6356418614805, 6112.083187521885, 6120.571559990803, 6123.479504242123, 6129.049997250433, 6134.171886795315, 6168.75324987, 5762.9135562966585, 5772.913406298909, 5835.066389377679, 5841.591088623798, 5850.6489610623075, 13397.933010334886, 13457.86542134576, 13498.392572334269, 13636.454541818315, 13638.727225455534, 2400.111958575321, 2617.4895895300424, 2920.73713365796, 3057.8623961921767, 3096.3962027404664, 10339.63811266602, 10512.526936287886, 10834.512446939907, 11227.326360418401, 11274.0, 1960.598077394145, 1990.2536548794203, 1994.1425187169511, 2051.0872661665567, 2077.55332603491, 59475.40524594742, 68730.65634671795, 80039.5998020012, 80294.598527007, 89202.55398723029, 11093.223474356879, 11171.608993685182, 11189.160812939062, 11218.270812397168, 11537.653870383901, 15866.603334916488, 15891.920540397225, 15972.680546389007, 16041.59896002586, 16052.678946421007, 7494.775156745306, 7883.6058197089915, 7901.209879012053, 7917.960410197912, 7924.801879953047, 7404.1855395906405, 7454.627268636546, 7478.813029674193, 7589.392848572212, 7617.695292188361, 4563.748993805372, 4665.95334046662, 4835.661503694708, 4899.167141585939, 4901.950980490149, 1938.5928954919343, 1956.6771482705194, 2020.3331602285136, 2030.3401394546831, 2047.0174316327948, 878.3280790195505, 992.9652462163934, 1003.242551873331, 1023.4729114506035, 1054.2514814481651, 20177.0, 20721.792782071985, 21193.576128477653, 21342.14631414726, 21915.67126493104, 1000.2048371544494, 1001.2020360413056, 1030.0935177044303, 1046.6232156423682, 1051.8790339111101, 11210.439478026066, 11715.297082175097, 11777.52889884395, 11816.291022538675, 11826.0, 518939.0, 539488.0, 539764.0, 540333.0, 550414.0, 88450.55774721214, 102710.75603195748, 103935.0, 105082.47458762811, 108837.0, 983.1593987141034, 1015.5582321690089, 1021.5403068619152, 1035.5495359518484, 1042.5621239079605, 19394.321198757974, 19422.8057719421, 19750.0, 19878.70181947243, 19894.403167904988, 45508.54491455043, 46573.53426465827, 46701.100381765646, 47568.286475703586, 47629.28556071731, 60908.69545652244, 63473.0, 64981.0, 66206.33793662002, 67381.66309168423, 7673.309402153833, 8049.27556519916, 8703.69537066225, 8929.330300227379, 9135.451872887648, 13935.442582296595, 14030.088044277089, 14089.15465072099, 14133.293335332986, 14248.857511424752, 11569.768604628029, 11795.0, 11971.341576213392, 12083.57707480234, 12119.394030298274, 11981.460834262305, 12220.938895305466, 12231.510739570318, 12416.689582760504, 12608.243505389706, 12368.134230603771, 12465.750684986431, 12553.121281510206, 12741.299228542519, 13006.089573730127, 8080.4343695940715, 8101.946746923098, 8116.431849770459, 8312.21034001769, 8325.375596830141, 9344.112309330603, 11089.22375433712, 11691.824622630838, 11737.765244695229, 12061.276323420623], "value": 9260.858941362601, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.9421623135072, 1509.5395904249203, 1609.2114863716774, 1782.2959930827315, 1782.6880295948215, 8609.569521523923, 9048.095190480948, 9131.543422828849, 9136.588853501591, 9144.634214631415, 7607.847843043136, 7817.960910195456, 7821.530708157515, 8013.519188848673, 8914.50970196639, 2250.077468238019, 2321.0599707118645, 2322.175627652176, 2327.138958585326, 2333.94165145871, 2332.2769941318247, 2346.683197768305, 2361.622140457527, 2368.6447032945025, 2374.1097088591778, 2343.847649902751, 2351.6590094436297, 2357.210334537932, 2361.4686695493547, 2379.524095180962, 2318.2929206591984, 2319.512902290504, 2330.7203135623663, 2335.5095429959642, 2337.275444612167, 2300.4708916949194, 2302.4013756423283, 2305.8385912986096, 2310.2838120182796, 2312.28319221042, 9069.455832650063, 9113.134252246025, 9119.863202051974, 9128.634854605807, 9129.22401595865, 5150.562202212816, 5295.4969277918535, 5301.284326615934, 5302.734863256822, 5309.070912590281, 3278.508223766454, 3326.9833650831833, 3449.6205417404067, 3450.3099380124077, 3454.1709989602564, 1162.5698491558178, 1183.8283448899947, 1196.3958201108385, 1197.7245233596323, 1209.4799236328463, 12560.874391256153, 12587.496500139983, 12588.937055314756, 12604.369781510979, 12607.747845043139, 4436.600705936481, 4501.48232953208, 4655.930161047587, 4656.837010704677, 4658.138244424792, 10586.682399528027, 10608.787824243473, 10623.36259824413, 10626.36241825493, 10631.362118272928, 5255.658382205144, 5276.261323414725, 5280.6039547034125, 5297.125974214211, 5297.576193904479, 4230.619244267969, 4269.786510674454, 4794.208955522369, 4847.103285892122, 4854.368932038812, 4007.7595344279434, 4011.5186177658866, 4079.7144199906315, 4093.099518106012, 4104.958950410458, 1261.4323554400557, 1278.7122897348022, 1301.8372703412074, 1303.419978109732, 1305.09948135786, 1022.7238645565805, 1026.7330494071596, 1039.1634734039171, 1040.47976011994, 1056.1920131099714, 6260.624362538262, 6263.4362907338555, 6351.4283714465, 6411.4229719325485, 6436.388543088399, 461.87529367070704, 474.23647926837765, 474.38093288259154, 476.09066682636, 481.30211193769173, 4283.378910058024, 4338.1323735252445, 4361.9127617447475, 4376.759278239728, 4456.509783923766, 10923.071538919123, 11063.889361106287, 11219.719507012227, 11254.718632034264, 11357.602483913024, 11098.944505277583, 11101.944490277498, 11105.722356941142, 11109.888901110886, 11199.72000699989, 7903.604819758989, 8188.01743790737, 8245.464044837066, 8254.669813207525, 8467.026291976379, 6169.259688837368, 6185.907211391923, 6349.555531112777, 6402.423781859655, 6421.8715625688155, 10370.27408081427, 10521.947390263, 10571.207159463225, 10656.094231990368, 10784.352938823695, 9283.535823208947, 10192.490375481344, 10806.18953578469, 10943.507542160782, 10943.945280273547, 7782.338501227459, 7892.526448413114, 7979.16218797018, 7989.001374828146, 7989.600519973862, 8419.789505262417, 8486.278666313432, 8521.147885211429, 8523.872141917753, 8536.871946920677, 7481.513701609378, 7764.495307804975, 7769.844603107793, 7830.138684744559, 7879.645415956181, 7577.772666820004, 7632.771016869503, 7656.119546252252, 7670.7698769037015, 7706.267904549283, 6770.458363330823, 6775.830604234933, 6816.147981502312, 6824.829379265558, 6826.044353790573, 4935.827246046371, 5163.690178589297, 5182.222666600041, 5295.25866378715, 5355.116405793001, 5072.54347108762, 5148.536631703165, 5178.326817513699, 5207.479252074762, 5219.8434046978655], "value": 4622.808129670913, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.382640635605, 333.2435371706227, 336.38945314254613, 338.66472192529403, 339.9065257054311, 5311.867203319919, 5336.8132115375965, 5338.225957236204, 5346.89306213876, 5393.541548968343, 5281.551068159211, 5291.708956007418, 5308.203769434586, 5312.4687531246855, 5324.387695415028, 3940.6847452203897, 4018.6584140348104, 4124.546299907008, 4125.711200215986, 4143.1713657268665, 4057.1337184894983, 4103.384492326145, 4114.773687447204, 4115.753054816721, 4133.140086960864, 3957.149212919222, 4103.589641035903, 4110.588941105895, 4120.876373708778, 4122.113745544707, 3999.6600288975474, 4100.384942258685, 4107.342825147962, 4115.794210289474, 4121.773302468363, 3651.707863370924, 3655.5430571178604, 3660.890173294805, 3662.230931504387, 3662.633736626343, 5138.409082955471, 5188.9881473112755, 5221.0, 5232.790688372461, 5239.423663397023, 4474.38921988084, 4696.107739529512, 4717.481077081518, 4732.532914796389, 4735.313379559944, 5494.478024587658, 5503.944960550383, 5511.0, 5514.779408823642, 5524.364698059734, 3841.1741475582885, 3963.2667956427863, 3983.462232598591, 3995.9400608990886, 4005.8798236052967, 5719.8570035749435, 5734.569907256972, 5737.627054241461, 5750.827475175752, 5761.452661997104, 4047.838086476567, 4108.007616306089, 4117.5176936302905, 4117.547069822317, 4123.134141830249, 3936.193080418507, 4167.499900012018, 4169.478140478764, 4172.833086676499, 4197.286461301586, 4682.484926658065, 4696.201645720235, 4709.529047095263, 4719.528047195253, 4770.189067858403, 5545.584081193927, 5547.362053363834, 5558.221848941152, 5562.499375056264, 5565.41563135873, 1358.531306699187, 1417.5109587192403, 1428.8785453236383, 1435.8635929586674, 1436.1526699247495, 912.493566070837, 914.2777206007313, 949.8290307744672, 975.453745902297, 982.6560703753772, 5290.391604965402, 5311.892542862392, 5318.946810531923, 5337.466253374709, 5337.519623233928, 5604.215409842625, 5613.691246981455, 5642.435756424325, 5672.376038635747, 5707.315122185364, 5146.562542183881, 5345.049057094133, 5350.812721554805, 5362.276092727432, 5374.35507739066, 2997.4754417976765, 3005.1285127312835, 3006.5189569669187, 3013.2015016020773, 3015.894443694504, 5624.521915637135, 5796.275465566804, 5825.533957283407, 5837.970810146007, 5838.416158384128, 5849.970750146222, 5898.941010589925, 5899.970500147472, 5907.55264960079, 5909.23179986599, 5915.935131676253, 5925.614835035709, 5953.821385358447, 5962.07587823888, 5984.2818861736, 5553.194786755817, 5733.541316694571, 5734.397888221677, 5734.799282025109, 5776.624519406225, 5586.776528938797, 5660.867826434812, 5670.376258611467, 5690.5163061140265, 5691.800786972436, 5567.805126820542, 5886.411358864079, 5897.64614123154, 5905.527557795452, 5927.911081333697, 4061.735987160825, 4076.3885417187657, 4099.651529620016, 4103.302438585507, 4121.9587804121575, 5737.196792449144, 5748.109043098327, 5750.597458178055, 5771.509421699203, 5784.132380143013, 5341.599380046434, 5605.803796867253, 5662.348829884616, 5671.829845104653, 5676.347220069745, 5538.944610554004, 5556.16657501378, 5558.749856256397, 5574.721263936706, 5605.747741351731, 5350.571954243729, 5461.453854614507, 5476.17857321405, 5544.805931792368, 5552.472515111058, 5157.303763991963, 5538.0, 5571.523546260325, 5618.101103823532, 5621.409751976147, 5480.712032672399, 5484.972575137099, 5488.945110549003, 5514.889702205854, 5516.3380394352935], "value": 4242.511065249548, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.3819420851735, 800.2317774936062, 801.6232370785729, 830.3813658824176, 870.5603670146575, 13642.249676267802, 14295.0, 14297.428102875872, 14321.069130506523, 14325.355359008843, 14491.0, 14712.0, 14722.926385368084, 14734.0, 14742.115473071623, 656.2518728649344, 675.3887731602907, 679.2731776998613, 680.7345135397175, 787.051602818605, 798.9480683755567, 803.441608082383, 804.493169303338, 804.7143264141233, 804.7706403674954, 844.8901642786429, 849.9915000850006, 852.6291063387446, 854.150120629974, 854.9444286121413, 849.9490030598184, 850.6427300533791, 850.8043150075456, 850.9191626795445, 852.1819053708448, 508.379776672459, 605.966671833049, 621.5121129912997, 622.0731110645106, 627.0562802981484, 14837.035592686549, 14839.851601483955, 14856.331465084097, 14894.478693245685, 14931.925340373336, 13825.79261311081, 14208.218547979857, 14273.35769890347, 14323.0, 14349.13905165683, 13501.459941602325, 13507.86492135076, 13648.181109133484, 13694.109882857583, 13939.0, 4566.1780879441685, 4573.611243044345, 4622.930656040162, 4780.952190478086, 4788.569028787426, 77314.61342693307, 78137.13971452163, 78665.60667196685, 79411.60294198492, 80114.0, 12085.516579336903, 12109.576164834365, 12141.575044873387, 12435.191712538655, 12734.872651273368, 22655.320340389815, 22694.546109077724, 22738.09047638109, 22763.430914226945, 22826.65760013601, 8654.264387527131, 8714.738557843275, 8723.47659140441, 8744.737657870273, 9121.270298376114, 4400.207962566768, 4609.078184363141, 4626.768661566908, 4732.02993386355, 4752.7861246244, 3537.20412907099, 3676.117731744362, 3684.373656478405, 3689.317476266899, 3694.07648087978, 620.4818976154871, 626.6553395632475, 629.2071989293431, 630.1115427247559, 647.6793986976382, 703.6503718658663, 703.6903762344552, 706.9929300706928, 740.3854800515559, 759.1194214710908, 10014.198864090855, 10041.19670426364, 10123.696289111338, 10212.957408518327, 10410.375377477376, 679.3681875855403, 686.1457485430572, 714.6248219684708, 720.0399582614147, 721.7834649605097, 4397.626201772821, 4537.909241815211, 4576.519465456146, 4578.404807375086, 4595.816167353335, 114907.0, 117427.0, 118288.40855795666, 118689.0, 119763.40118299353, 102410.48794756127, 103692.0, 104998.0, 105080.0, 105261.0, 58385.41614583885, 59272.0, 88406.53956916838, 89150.0, 90507.54746226227, 8867.822643547222, 8945.59744811498, 8956.865647015169, 9051.094890510896, 9239.861402079108, 52434.0, 53812.0, 54616.453835461136, 55258.44741552533, 56531.15203272036, 47084.0, 62829.0, 77149.61425192837, 77352.2264777345, 77719.83420248587, 11831.230969986924, 11836.76326473483, 11883.346415947206, 11896.107791915814, 12328.260304381765, 12856.42146103409, 12881.935590321988, 13124.868751312364, 13209.207447553177, 13617.931910340385, 11777.587784427504, 11834.0, 12151.635450936486, 12380.566680166152, 12842.550510732079, 10990.835137472784, 11098.223124381217, 11400.144989126014, 11806.409679515818, 12322.938385308016, 12060.0, 12060.216085954386, 12146.696332591757, 12371.938140309241, 12592.433340499516, 9443.622255109993, 9497.667581634609, 9609.183219426428, 9718.319717619699, 9953.800923981335, 9745.853812192965, 9991.900080999098, 10001.699949001542, 10833.133349332193, 11124.276922000045], "value": 7435.693540364757, "unit": "score"}], "name": "dromaeo_css", "value": 7250.904787127489, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 112.0, 111.0, 110.0, 111.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 119.0, 166.0, 117.0, 117.0, 146.0, 115.0, 148.0, 118.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 161.0, 161.0, 161.0, 163.0, 161.0, 163.0, 159.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 131.0, 76.0, 76.0, 74.0, 74.0, 110.0, 75.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 135.0, 85.0, 84.0, 84.0, 120.0, 84.0, 84.0, 128.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 149.0, 148.0, 150.0, 149.0, 149.0, 149.0, 145.0], "value": 148.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 49.0, 49.0, 50.0, 50.0, 49.0, 50.0, 50.0, 50.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 61.0, 63.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 84.0, 75.0, 75.0, 75.0, 76.0, 75.0, 76.0, 74.0, 74.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 117.0, 117.0, 118.0, 129.0, 133.0, 123.0, 132.0, 160.0, 128.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 159.0, 157.0, 156.0, 153.0, 158.0, 162.0, 157.0, 155.0], "value": 157.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 63.0, 70.0, 62.0, 64.0, 65.0, 60.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1481.6, "alertThreshold": 2.0}]} 06:05:38 INFO - Return code: 0 06:05:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:05:38 INFO - # TBPL SUCCESS # 06:05:38 INFO - Running post-action listener: _resource_record_post_action 06:05:38 INFO - ##### 06:05:38 INFO - ##### Finished run-tests step (success) 06:05:38 INFO - ##### 06:05:38 INFO - Running post-run listener: _resource_record_post_run 06:05:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557195264; Read time: 12; Write time: 378164 06:05:38 INFO - TinderboxPrint: CPU usage
13.0% 06:05:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 06:05:38 INFO - TinderboxPrint: I/O write bytes / time
557,195,264 / 378,164 06:05:38 INFO - TinderboxPrint: CPU idle
8,265.5 (87.0%) 06:05:38 INFO - TinderboxPrint: CPU user
1,193.0 (12.6%) 06:05:38 INFO - TinderboxPrint: Swap in / out
0 / 0 06:05:38 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 257495040; Read time: 0; Write time: 200972 06:05:38 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 299700224; Read time: 0; Write time: 177192 06:05:39 INFO - Running post-run listener: _upload_blobber_files 06:05:39 INFO - Blob upload gear active. 06:05:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:05:39 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 06:05:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:05:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:05:39 INFO - (blobuploader) - INFO - Open directory for files ... 06:05:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:05:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:05:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:43 INFO - (blobuploader) - INFO - Done attempting. 06:05:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:05:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:44 INFO - (blobuploader) - INFO - Done attempting. 06:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:45 INFO - (blobuploader) - INFO - Done attempting. 06:05:45 INFO - (blobuploader) - INFO - Iteration through files over. 06:05:45 INFO - Return code: 0 06:05:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:05:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:05:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64763832dd13d7d1129b637cd3fbb12142e7a61428dec99495bbb2ffaa26a72f9350e9eba1d00b4dce93c8900ea1102d898a2c24e18a14d39e5043f50e55fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac5ce909bf8f836d89097fc341c641e136ec280015739b458150e6b8c6c432b83074d21f3e416121ac4af1efb80828c7303f05cd29495419cdc6b3bb32ebc98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55352b43e4ab3bdd452acf6c29c0c6367e21e55a9672f32ed8eda367512c27770e0e64f1bff490d2e690364de84320b6527fea2831fadde0876d271f9dfbc89"} 06:05:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:05:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:05:45 INFO - Contents: 06:05:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64763832dd13d7d1129b637cd3fbb12142e7a61428dec99495bbb2ffaa26a72f9350e9eba1d00b4dce93c8900ea1102d898a2c24e18a14d39e5043f50e55fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac5ce909bf8f836d89097fc341c641e136ec280015739b458150e6b8c6c432b83074d21f3e416121ac4af1efb80828c7303f05cd29495419cdc6b3bb32ebc98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55352b43e4ab3bdd452acf6c29c0c6367e21e55a9672f32ed8eda367512c27770e0e64f1bff490d2e690364de84320b6527fea2831fadde0876d271f9dfbc89"} 06:05:45 INFO - Running post-run listener: copy_logs_to_upload_dir 06:05:45 INFO - Copying logs to upload dir... 06:05:45 INFO - mkdir: /builds/slave/test/build/upload/logs 06:05:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.236549 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-18 06:05:45.098365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 06:05:45.101829) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64763832dd13d7d1129b637cd3fbb12142e7a61428dec99495bbb2ffaa26a72f9350e9eba1d00b4dce93c8900ea1102d898a2c24e18a14d39e5043f50e55fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac5ce909bf8f836d89097fc341c641e136ec280015739b458150e6b8c6c432b83074d21f3e416121ac4af1efb80828c7303f05cd29495419cdc6b3bb32ebc98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55352b43e4ab3bdd452acf6c29c0c6367e21e55a9672f32ed8eda367512c27770e0e64f1bff490d2e690364de84320b6527fea2831fadde0876d271f9dfbc89"} build_url:https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011398 build_url: 'https://queue.taskcluster.net/v1/task/es3zXY9JTN6Xr-oIT3msHQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e64763832dd13d7d1129b637cd3fbb12142e7a61428dec99495bbb2ffaa26a72f9350e9eba1d00b4dce93c8900ea1102d898a2c24e18a14d39e5043f50e55fe0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac5ce909bf8f836d89097fc341c641e136ec280015739b458150e6b8c6c432b83074d21f3e416121ac4af1efb80828c7303f05cd29495419cdc6b3bb32ebc98d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55352b43e4ab3bdd452acf6c29c0c6367e21e55a9672f32ed8eda367512c27770e0e64f1bff490d2e690364de84320b6527fea2831fadde0876d271f9dfbc89"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 06:05:45.127042) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 06:05:45.127387) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1468845568.723732-2101419875 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 06:05:45.210075) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 06:05:45.210390) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 06:05:45.210717) ========= ========= Total master_lag: 0.15 =========