builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-062 starttime: 1472520568.28 results: success (0) buildid: 20160829174130 builduid: 8c00ce014b624fe2a83b5d52d1c191bb revision: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.281289) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.281789) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.282151) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.326672) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.327666) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-29 18:29:28-- 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% 225M=0s 2016-08-29 18:29:28 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173901 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.523077) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.523423) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022154 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.572461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:28.572903) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f --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 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f --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=a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-29 18:29:28,827 truncating revision to first 12 chars 2016-08-29 18:29:28,827 Setting DEBUG logging. 2016-08-29 18:29:28,827 attempt 1/10 2016-08-29 18:29:28,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c0d74730bd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 18:29:29,167 unpacking tar archive at: mozilla-central-6c0d74730bd5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.660195 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:29.246940) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:29.247298) ========= script_repo_revision: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:29.247791) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:29.248103) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:29.259163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-29 18:29:29.259472) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:29:29 INFO - MultiFileLogger online at 20160829 18:29:29 in /builds/slave/test 18:29:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:29:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:29 INFO - {'append_to_log': False, 18:29:29 INFO - 'base_work_dir': '/builds/slave/test', 18:29:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:29:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:29 INFO - 'branch': 'Firefox-Non-PGO', 18:29:29 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:29 INFO - 'config_files': ('talos/linux_config.py',), 18:29:29 INFO - 'default_actions': ('clobber', 18:29:29 INFO - 'read-buildbot-config', 18:29:29 INFO - 'download-and-extract', 18:29:29 INFO - 'populate-webroot', 18:29:29 INFO - 'create-virtualenv', 18:29:29 INFO - 'install', 18:29:29 INFO - 'run-tests'), 18:29:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:29 INFO - 'download_minidump_stackwalk': True, 18:29:29 INFO - 'download_symbols': 'ondemand', 18:29:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:29 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:29 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:29 INFO - 'installer_path': 'installer.exe', 18:29:29 INFO - 'log_level': 'info', 18:29:29 INFO - 'log_name': 'talos', 18:29:29 INFO - 'log_to_console': True, 18:29:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:29:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:29:29 INFO - 'opt_config_files': (), 18:29:29 INFO - 'pip_index': False, 18:29:29 INFO - 'sps_profile': False, 18:29:29 INFO - 'sps_profile_interval': 0, 18:29:29 INFO - 'suite': 'dromaeojs', 18:29:29 INFO - 'system_bits': '32', 18:29:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:29 INFO - 'title': 'talos-linux64-ix-062', 18:29:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:29 INFO - 'use_talos_json': True, 18:29:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:29 INFO - 'work_dir': 'build'} 18:29:29 INFO - [mozharness: 2016-08-30 01:29:29.611052Z] Running clobber step. 18:29:29 INFO - Running pre-action listener: _resource_record_pre_action 18:29:29 INFO - Running main action method: clobber 18:29:29 INFO - rmtree: /builds/slave/test/build 18:29:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:30 INFO - Running post-action listener: _resource_record_post_action 18:29:30 INFO - [mozharness: 2016-08-30 01:29:30.073594Z] Finished clobber step (success) 18:29:30 INFO - [mozharness: 2016-08-30 01:29:30.073762Z] Running read-buildbot-config step. 18:29:30 INFO - Running pre-action listener: _resource_record_pre_action 18:29:30 INFO - Running main action method: read_buildbot_config 18:29:30 INFO - Using buildbot properties: 18:29:30 INFO - { 18:29:30 INFO - "project": "", 18:29:30 INFO - "product": "firefox", 18:29:30 INFO - "script_repo_revision": "production", 18:29:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:29:30 INFO - "repository": "", 18:29:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:29:30 INFO - "buildid": "20160829174130", 18:29:30 INFO - "pgo_build": "False", 18:29:30 INFO - "basedir": "/builds/slave/test", 18:29:30 INFO - "buildnumber": 695, 18:29:30 INFO - "slavename": "talos-linux64-ix-062", 18:29:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:29:30 INFO - "platform": "ubuntu64_hw", 18:29:30 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:29:30 INFO - "branch": "mozilla-central", 18:29:30 INFO - "repo_path": "mozilla-central", 18:29:30 INFO - "revision": "6c0d74730bd57da6c1c9bf3d05608cd8156bb65f", 18:29:30 INFO - "stage_platform": "linux64", 18:29:30 INFO - "builduid": "8c00ce014b624fe2a83b5d52d1c191bb", 18:29:30 INFO - "slavebuilddir": "test" 18:29:30 INFO - } 18:29:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:29:30 INFO - Running post-action listener: _resource_record_post_action 18:29:30 INFO - [mozharness: 2016-08-30 01:29:30.076202Z] Finished read-buildbot-config step (success) 18:29:30 INFO - [mozharness: 2016-08-30 01:29:30.076292Z] Running download-and-extract step. 18:29:30 INFO - Running pre-action listener: _resource_record_pre_action 18:29:30 INFO - Running main action method: download_and_extract 18:29:30 INFO - mkdir: /builds/slave/test/build/tests 18:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:30 INFO - trying https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:29:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:29:30 INFO - Downloaded 1361 bytes. 18:29:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:29:30 INFO - Using the following test package requirements: 18:29:30 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:29:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:29:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'jsshell-linux-x86_64.zip'], 18:29:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:29:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:29:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:29:30 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:29:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:29:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:29:30 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:29:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:30 INFO - trying https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:29:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:31 INFO - Downloaded 20315589 bytes. 18:29:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:29:35 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:29:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:35 INFO - trying https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:29:35 INFO - Downloading https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:29:36 INFO - Downloaded 20315589 bytes. 18:29:36 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:41 INFO - trying https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:41 INFO - Downloading https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:29:41 INFO - Downloaded 14413998 bytes. 18:29:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 18:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:42 INFO - trying https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:29:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:29:43 INFO - Downloaded 55842234 bytes. 18:29:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:29:43 INFO - mkdir: /builds/slave/test/properties 18:29:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:29:43 INFO - Writing to file /builds/slave/test/properties/build_url 18:29:43 INFO - Contents: 18:29:43 INFO - build_url:https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:29:43 INFO - Running post-action listener: _resource_record_post_action 18:29:43 INFO - Running post-action listener: set_extra_try_arguments 18:29:43 INFO - [mozharness: 2016-08-30 01:29:43.916442Z] Finished download-and-extract step (success) 18:29:43 INFO - [mozharness: 2016-08-30 01:29:43.916745Z] Running populate-webroot step. 18:29:43 INFO - Running pre-action listener: _resource_record_pre_action 18:29:43 INFO - Running main action method: populate_webroot 18:29:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:29:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:29:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:29:43 INFO - '--tppagecycles', 18:29:43 INFO - '7'], 18:29:43 INFO - 'tests': ['tsvgm']}}, 18:29:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:29:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:29:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:29:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:29:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:29:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['damp', 'tps']}, 18:29:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'tests': ['damp', 'tps']}, 18:29:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['dromaeo_dom']}, 18:29:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:29:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['basic_compositor_video']}, 18:29:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:29:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['a11yr', 18:29:43 INFO - 'ts_paint', 18:29:43 INFO - 'tpaint', 18:29:43 INFO - 'sessionrestore', 18:29:43 INFO - 'sessionrestore_no_auto_restore', 18:29:43 INFO - 'tabpaint']}, 18:29:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['tsvgx', 18:29:43 INFO - 'tsvgr_opacity', 18:29:43 INFO - 'tart', 18:29:43 INFO - 'tscrollx', 18:29:43 INFO - 'cart']}, 18:29:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:29:43 INFO - 'tsvgr_opacity', 18:29:43 INFO - 'tart', 18:29:43 INFO - 'tscrollx', 18:29:43 INFO - 'cart']}, 18:29:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-e10s'], 18:29:43 INFO - 'tests': ['tp5o']}, 18:29:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'tests': ['tp5o']}, 18:29:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'talos_options': ['--disable-e10s', 18:29:43 INFO - '--xperf_path', 18:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:43 INFO - 'tests': ['tp5n']}, 18:29:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:29:43 INFO - 'talos_options': ['--xperf_path', 18:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:29:43 INFO - 'tests': ['tp5n']}}, 18:29:43 INFO - 'talos.zip': {'path': '', 18:29:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:29:43 INFO - Running post-action listener: _resource_record_post_action 18:29:43 INFO - [mozharness: 2016-08-30 01:29:43.937518Z] Finished populate-webroot step (success) 18:29:43 INFO - [mozharness: 2016-08-30 01:29:43.937633Z] Running create-virtualenv step. 18:29:43 INFO - Running pre-action listener: _resource_record_pre_action 18:29:43 INFO - Running main action method: create_virtualenv 18:29:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:29:43 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 18:29:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:29:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:29:44 INFO - Using real prefix '/usr' 18:29:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:29:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:29:46 INFO - Installing pip.................done. 18:29:46 INFO - Return code: 0 18:29:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:46 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:46 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 18:29:46 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 18:29:46 INFO - Using env: {'DISPLAY': ':0', 18:29:46 INFO - 'HOME': '/home/cltbld', 18:29:46 INFO - 'LANG': 'en_US.UTF-8', 18:29:46 INFO - 'LANGUAGE': 'en_US:en', 18:29:46 INFO - 'LOGNAME': 'cltbld', 18:29:46 INFO - 'MAIL': '/var/mail/cltbld', 18:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:46 INFO - 'MOZ_NO_REMOTE': '1', 18:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:46 INFO - 'NO_EM_RESTART': '1', 18:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:46 INFO - 'PWD': '/builds/slave/test', 18:29:46 INFO - 'SHELL': '/bin/bash', 18:29:46 INFO - 'SHLVL': '1', 18:29:46 INFO - 'TERM': 'linux', 18:29:46 INFO - 'TMOUT': '86400', 18:29:46 INFO - 'USER': 'cltbld', 18:29:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:46 INFO - '_': '/tools/buildbot/bin/python'} 18:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:46 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 18:29:46 INFO - Cleaning up... 18:29:46 INFO - Return code: 0 18:29:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:46 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:46 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 18:29:46 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 18:29:46 INFO - Using env: {'DISPLAY': ':0', 18:29:46 INFO - 'HOME': '/home/cltbld', 18:29:46 INFO - 'LANG': 'en_US.UTF-8', 18:29:46 INFO - 'LANGUAGE': 'en_US:en', 18:29:46 INFO - 'LOGNAME': 'cltbld', 18:29:46 INFO - 'MAIL': '/var/mail/cltbld', 18:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:46 INFO - 'MOZ_NO_REMOTE': '1', 18:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:46 INFO - 'NO_EM_RESTART': '1', 18:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:46 INFO - 'PWD': '/builds/slave/test', 18:29:46 INFO - 'SHELL': '/bin/bash', 18:29:46 INFO - 'SHLVL': '1', 18:29:46 INFO - 'TERM': 'linux', 18:29:46 INFO - 'TMOUT': '86400', 18:29:46 INFO - 'USER': 'cltbld', 18:29:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:46 INFO - '_': '/tools/buildbot/bin/python'} 18:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:46 INFO - Downloading/unpacking psutil>=3.1.1 18:29:46 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 18:29:46 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 18:29:46 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 18:29:46 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 18:29:46 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 18:29:46 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 18:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:29:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:48 INFO - Installing collected packages: psutil 18:29:48 INFO - Running setup.py install for psutil 18:29:49 INFO - building 'psutil._psutil_linux' extension 18:29:49 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 18:29:49 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 18:29:49 INFO - building 'psutil._psutil_posix' extension 18:29:49 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 18:29:49 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 18:29:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:29:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:29:49 INFO - Successfully installed psutil 18:29:49 INFO - Cleaning up... 18:29:49 INFO - Return code: 0 18:29:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:49 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:49 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 18:29:49 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 18:29:49 INFO - Using env: {'DISPLAY': ':0', 18:29:49 INFO - 'HOME': '/home/cltbld', 18:29:49 INFO - 'LANG': 'en_US.UTF-8', 18:29:49 INFO - 'LANGUAGE': 'en_US:en', 18:29:49 INFO - 'LOGNAME': 'cltbld', 18:29:49 INFO - 'MAIL': '/var/mail/cltbld', 18:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:49 INFO - 'MOZ_NO_REMOTE': '1', 18:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:49 INFO - 'NO_EM_RESTART': '1', 18:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:49 INFO - 'PWD': '/builds/slave/test', 18:29:49 INFO - 'SHELL': '/bin/bash', 18:29:49 INFO - 'SHLVL': '1', 18:29:49 INFO - 'TERM': 'linux', 18:29:49 INFO - 'TMOUT': '86400', 18:29:49 INFO - 'USER': 'cltbld', 18:29:49 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:49 INFO - '_': '/tools/buildbot/bin/python'} 18:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:29:51 INFO - Installing collected packages: mozsystemmonitor 18:29:51 INFO - Running setup.py install for mozsystemmonitor 18:29:51 INFO - Successfully installed mozsystemmonitor 18:29:51 INFO - Cleaning up... 18:29:51 INFO - Return code: 0 18:29:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:51 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:51 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 18:29:51 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 18:29:51 INFO - Using env: {'DISPLAY': ':0', 18:29:51 INFO - 'HOME': '/home/cltbld', 18:29:51 INFO - 'LANG': 'en_US.UTF-8', 18:29:51 INFO - 'LANGUAGE': 'en_US:en', 18:29:51 INFO - 'LOGNAME': 'cltbld', 18:29:51 INFO - 'MAIL': '/var/mail/cltbld', 18:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:51 INFO - 'MOZ_NO_REMOTE': '1', 18:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:51 INFO - 'NO_EM_RESTART': '1', 18:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:51 INFO - 'PWD': '/builds/slave/test', 18:29:51 INFO - 'SHELL': '/bin/bash', 18:29:51 INFO - 'SHLVL': '1', 18:29:51 INFO - 'TERM': 'linux', 18:29:51 INFO - 'TMOUT': '86400', 18:29:51 INFO - 'USER': 'cltbld', 18:29:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:51 INFO - '_': '/tools/buildbot/bin/python'} 18:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:51 INFO - Downloading/unpacking jsonschema==2.5.1 18:29:51 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 18:29:51 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 18:29:51 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 18:29:51 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 18:29:51 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 18:29:51 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 18:29:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:29:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:29:54 INFO - Downloading functools32-3.2.3-2.tar.gz 18:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:29:54 INFO - warning: no files found matching '*.txt' 18:29:54 INFO - no previously-included directories found matching 'build' 18:29:54 INFO - no previously-included directories found matching 'dist' 18:29:54 INFO - no previously-included directories found matching '.git*' 18:29:54 INFO - Installing collected packages: jsonschema, functools32 18:29:54 INFO - Running setup.py install for functools32 18:29:54 INFO - warning: no files found matching '*.txt' 18:29:54 INFO - no previously-included directories found matching 'build' 18:29:54 INFO - no previously-included directories found matching 'dist' 18:29:54 INFO - no previously-included directories found matching '.git*' 18:29:54 INFO - Successfully installed jsonschema functools32 18:29:54 INFO - Cleaning up... 18:29:54 INFO - Return code: 0 18:29:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:29:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:29:54 INFO - Using env: {'DISPLAY': ':0', 18:29:54 INFO - 'HOME': '/home/cltbld', 18:29:54 INFO - 'LANG': 'en_US.UTF-8', 18:29:54 INFO - 'LANGUAGE': 'en_US:en', 18:29:54 INFO - 'LOGNAME': 'cltbld', 18:29:54 INFO - 'MAIL': '/var/mail/cltbld', 18:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:54 INFO - 'MOZ_NO_REMOTE': '1', 18:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:54 INFO - 'NO_EM_RESTART': '1', 18:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:54 INFO - 'PWD': '/builds/slave/test', 18:29:54 INFO - 'SHELL': '/bin/bash', 18:29:54 INFO - 'SHLVL': '1', 18:29:54 INFO - 'TERM': 'linux', 18:29:54 INFO - 'TMOUT': '86400', 18:29:54 INFO - 'USER': 'cltbld', 18:29:54 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:54 INFO - '_': '/tools/buildbot/bin/python'} 18:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:29:54 INFO - Cleaning up... 18:29:54 INFO - Return code: 0 18:29:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:54 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:54 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 18:29:54 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 18:29:54 INFO - Using env: {'DISPLAY': ':0', 18:29:54 INFO - 'HOME': '/home/cltbld', 18:29:54 INFO - 'LANG': 'en_US.UTF-8', 18:29:54 INFO - 'LANGUAGE': 'en_US:en', 18:29:54 INFO - 'LOGNAME': 'cltbld', 18:29:54 INFO - 'MAIL': '/var/mail/cltbld', 18:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:54 INFO - 'MOZ_NO_REMOTE': '1', 18:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:54 INFO - 'NO_EM_RESTART': '1', 18:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:54 INFO - 'PWD': '/builds/slave/test', 18:29:54 INFO - 'SHELL': '/bin/bash', 18:29:54 INFO - 'SHLVL': '1', 18:29:54 INFO - 'TERM': 'linux', 18:29:54 INFO - 'TMOUT': '86400', 18:29:54 INFO - 'USER': 'cltbld', 18:29:54 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:54 INFO - '_': '/tools/buildbot/bin/python'} 18:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:54 INFO - Downloading/unpacking blobuploader==1.2.4 18:29:54 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 18:29:54 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 18:29:54 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 18:29:54 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 18:29:54 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 18:29:54 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 18:29:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:29:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:29:56 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 18:29:56 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 18:29:56 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 18:29:56 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 18:29:56 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 18:29:56 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 18:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:29:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:29:57 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 18:29:57 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 18:29:57 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 18:29:57 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 18:29:57 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 18:29:57 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 18:29:57 INFO - Downloading docopt-0.6.1.tar.gz 18:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:29:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:29:57 INFO - Running setup.py install for blobuploader 18:29:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:29:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:29:57 INFO - Running setup.py install for requests 18:29:57 INFO - Running setup.py install for docopt 18:29:57 INFO - Successfully installed blobuploader requests docopt 18:29:57 INFO - Cleaning up... 18:29:58 INFO - Return code: 0 18:29:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:29:58 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:29:58 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 18:29:58 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 18:29:58 INFO - Using env: {'DISPLAY': ':0', 18:29:58 INFO - 'HOME': '/home/cltbld', 18:29:58 INFO - 'LANG': 'en_US.UTF-8', 18:29:58 INFO - 'LANGUAGE': 'en_US:en', 18:29:58 INFO - 'LOGNAME': 'cltbld', 18:29:58 INFO - 'MAIL': '/var/mail/cltbld', 18:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:29:58 INFO - 'MOZ_NO_REMOTE': '1', 18:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:29:58 INFO - 'NO_EM_RESTART': '1', 18:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:29:58 INFO - 'PWD': '/builds/slave/test', 18:29:58 INFO - 'SHELL': '/bin/bash', 18:29:58 INFO - 'SHLVL': '1', 18:29:58 INFO - 'TERM': 'linux', 18:29:58 INFO - 'TMOUT': '86400', 18:29:58 INFO - 'USER': 'cltbld', 18:29:58 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:29:58 INFO - '_': '/tools/buildbot/bin/python'} 18:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:29:58 INFO - Running setup.py (path:/tmp/pip-4S_d66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:29:58 INFO - Running setup.py (path:/tmp/pip-KB6rNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:29:58 INFO - Running setup.py (path:/tmp/pip-lrBYmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:29:58 INFO - Running setup.py (path:/tmp/pip-SpAcgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:29:58 INFO - Running setup.py (path:/tmp/pip-RH6RQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:29:58 INFO - Running setup.py (path:/tmp/pip-qIsyA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:29:58 INFO - Running setup.py (path:/tmp/pip-ahhDKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:29:58 INFO - Running setup.py (path:/tmp/pip-qkfSmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:29:58 INFO - Running setup.py (path:/tmp/pip-nw9Zc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:29:58 INFO - Running setup.py (path:/tmp/pip-MtjzMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:29:58 INFO - Running setup.py (path:/tmp/pip-RkOv7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:29:59 INFO - Running setup.py (path:/tmp/pip-rsQi37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:29:59 INFO - Running setup.py (path:/tmp/pip-UDPK0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:29:59 INFO - Running setup.py (path:/tmp/pip-Ge56Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:59 INFO - Running setup.py (path:/tmp/pip-Kl1CMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:29:59 INFO - Running setup.py (path:/tmp/pip-SI_OYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:29:59 INFO - Running setup.py (path:/tmp/pip-BUldGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:29:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:29:59 INFO - Running setup.py install for manifestparser 18:29:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:29:59 INFO - Running setup.py install for mozcrash 18:29:59 INFO - Running setup.py install for mozdebug 18:29:59 INFO - Running setup.py install for mozdevice 18:29:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:29:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:29:59 INFO - Running setup.py install for mozfile 18:30:00 INFO - Running setup.py install for mozhttpd 18:30:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for mozinfo 18:30:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for mozInstall 18:30:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for mozleak 18:30:00 INFO - Running setup.py install for mozlog 18:30:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for moznetwork 18:30:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for mozprocess 18:30:00 INFO - Running setup.py install for mozprofile 18:30:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for mozrunner 18:30:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:00 INFO - Running setup.py install for mozscreenshot 18:30:01 INFO - Running setup.py install for moztest 18:30:01 INFO - Running setup.py install for mozversion 18:30:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:01 INFO - Cleaning up... 18:30:01 INFO - Return code: 0 18:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:01 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:30:01 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 18:30:01 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 18:30:01 INFO - Using env: {'DISPLAY': ':0', 18:30:01 INFO - 'HOME': '/home/cltbld', 18:30:01 INFO - 'LANG': 'en_US.UTF-8', 18:30:01 INFO - 'LANGUAGE': 'en_US:en', 18:30:01 INFO - 'LOGNAME': 'cltbld', 18:30:01 INFO - 'MAIL': '/var/mail/cltbld', 18:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:01 INFO - 'MOZ_NO_REMOTE': '1', 18:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:01 INFO - 'NO_EM_RESTART': '1', 18:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:01 INFO - 'PWD': '/builds/slave/test', 18:30:01 INFO - 'SHELL': '/bin/bash', 18:30:01 INFO - 'SHLVL': '1', 18:30:01 INFO - 'TERM': 'linux', 18:30:01 INFO - 'TMOUT': '86400', 18:30:01 INFO - 'USER': 'cltbld', 18:30:01 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:01 INFO - '_': '/tools/buildbot/bin/python'} 18:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:01 INFO - Running setup.py (path:/tmp/pip-oiM6eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:01 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)) 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:01 INFO - Running setup.py (path:/tmp/pip-cMXGp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:01 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)) 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:01 INFO - Running setup.py (path:/tmp/pip-kbADpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:01 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)) 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:01 INFO - Running setup.py (path:/tmp/pip-3nOl2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:01 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)) 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:01 INFO - Running setup.py (path:/tmp/pip-vTI9uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:01 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)) 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:01 INFO - Running setup.py (path:/tmp/pip-KHA9lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:01 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)) 18:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:01 INFO - Running setup.py (path:/tmp/pip-NqinNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:02 INFO - Running setup.py (path:/tmp/pip-hXEvw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:02 INFO - Running setup.py (path:/tmp/pip-9LCNH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:02 INFO - Running setup.py (path:/tmp/pip-knFnGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:02 INFO - Running setup.py (path:/tmp/pip-B1yTj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:02 INFO - Running setup.py (path:/tmp/pip-iD2SRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:02 INFO - Running setup.py (path:/tmp/pip-zdzoyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:02 INFO - Running setup.py (path:/tmp/pip-IjSt12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:02 INFO - Running setup.py (path:/tmp/pip-cPbscP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:02 INFO - Running setup.py (path:/tmp/pip-EHRLYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:02 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)) 18:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:02 INFO - Running setup.py (path:/tmp/pip-62QppY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:02 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)) 18:30:02 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)) 18:30:02 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)) 18:30:02 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)) 18:30:02 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)) 18:30:02 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)) 18:30:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:02 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 18:30:02 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 18:30:02 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 18:30:02 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 18:30:02 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 18:30:02 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 18:30:04 INFO - Downloading blessings-1.6.tar.gz 18:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:04 INFO - Installing collected packages: blessings 18:30:04 INFO - Running setup.py install for blessings 18:30:04 INFO - Successfully installed blessings 18:30:04 INFO - Cleaning up... 18:30:04 INFO - Return code: 0 18:30:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:05 INFO - Reading from file tmpfile_stdout 18:30:05 INFO - Current package versions: 18:30:05 INFO - argparse == 1.2.1 18:30:05 INFO - blessings == 1.6 18:30:05 INFO - blobuploader == 1.2.4 18:30:05 INFO - docopt == 0.6.1 18:30:05 INFO - functools32 == 3.2.3-2 18:30:05 INFO - jsonschema == 2.5.1 18:30:05 INFO - manifestparser == 1.1 18:30:05 INFO - mozInstall == 1.12 18:30:05 INFO - mozcrash == 0.17 18:30:05 INFO - mozdebug == 0.1 18:30:05 INFO - mozdevice == 0.48 18:30:05 INFO - mozfile == 1.2 18:30:05 INFO - mozhttpd == 0.7 18:30:05 INFO - mozinfo == 0.9 18:30:05 INFO - mozleak == 0.1 18:30:05 INFO - mozlog == 3.3 18:30:05 INFO - moznetwork == 0.27 18:30:05 INFO - mozprocess == 0.23 18:30:05 INFO - mozprofile == 0.28 18:30:05 INFO - mozrunner == 6.12 18:30:05 INFO - mozscreenshot == 0.1 18:30:05 INFO - mozsystemmonitor == 0.3 18:30:05 INFO - moztest == 0.7 18:30:05 INFO - mozversion == 1.4 18:30:05 INFO - psutil == 3.1.1 18:30:05 INFO - requests == 1.2.3 18:30:05 INFO - wsgiref == 0.1.2 18:30:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:30:05 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 18:30:05 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 18:30:05 INFO - Using env: {'DISPLAY': ':0', 18:30:05 INFO - 'HOME': '/home/cltbld', 18:30:05 INFO - 'LANG': 'en_US.UTF-8', 18:30:05 INFO - 'LANGUAGE': 'en_US:en', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MAIL': '/var/mail/cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SHLVL': '1', 18:30:05 INFO - 'TERM': 'linux', 18:30:05 INFO - 'TMOUT': '86400', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - '_': '/tools/buildbot/bin/python'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 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)) 18:30:05 INFO - Cleaning up... 18:30:05 INFO - Return code: 0 18:30:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:05 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 0x7fd83ebbaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e8230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', '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 18:30:05 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 18:30:05 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 18:30:05 INFO - Using env: {'DISPLAY': ':0', 18:30:05 INFO - 'HOME': '/home/cltbld', 18:30:05 INFO - 'LANG': 'en_US.UTF-8', 18:30:05 INFO - 'LANGUAGE': 'en_US:en', 18:30:05 INFO - 'LOGNAME': 'cltbld', 18:30:05 INFO - 'MAIL': '/var/mail/cltbld', 18:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:05 INFO - 'MOZ_NO_REMOTE': '1', 18:30:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:05 INFO - 'NO_EM_RESTART': '1', 18:30:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:05 INFO - 'PWD': '/builds/slave/test', 18:30:05 INFO - 'SHELL': '/bin/bash', 18:30:05 INFO - 'SHLVL': '1', 18:30:05 INFO - 'TERM': 'linux', 18:30:05 INFO - 'TMOUT': '86400', 18:30:05 INFO - 'USER': 'cltbld', 18:30:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:05 INFO - '_': '/tools/buildbot/bin/python'} 18:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:05 INFO - Cleaning up... 18:30:05 INFO - Return code: 0 18:30:05 INFO - Running post-action listener: _resource_record_post_action 18:30:05 INFO - Running post-action listener: _start_resource_monitoring 18:30:05 INFO - Starting resource monitoring. 18:30:05 INFO - [mozharness: 2016-08-30 01:30:05.581447Z] Finished create-virtualenv step (success) 18:30:05 INFO - [mozharness: 2016-08-30 01:30:05.581985Z] Running install step. 18:30:05 INFO - Running pre-action listener: _resource_record_pre_action 18:30:05 INFO - Running main action method: install 18:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:05 INFO - Reading from file tmpfile_stdout 18:30:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:05 INFO - Reading from file tmpfile_stdout 18:30:05 INFO - Output received: 18:30:05 INFO - Usage: mozinstall [options] installer 18:30:05 INFO - Options: 18:30:05 INFO - -h, --help show this help message and exit 18:30:05 INFO - -d DEST, --destination=DEST 18:30:05 INFO - Directory to install application into. [default: 18:30:05 INFO - "/builds/slave/test"] 18:30:05 INFO - --app=APP Application being installed. [default: firefox] 18:30:05 INFO - mkdir: /builds/slave/test/build/application 18:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:30:22 INFO - Reading from file tmpfile_stdout 18:30:22 INFO - Output received: 18:30:22 INFO - /builds/slave/test/build/application/firefox/firefox 18:30:22 INFO - Running post-action listener: _resource_record_post_action 18:30:22 INFO - [mozharness: 2016-08-30 01:30:22.010752Z] Finished install step (success) 18:30:22 INFO - [mozharness: 2016-08-30 01:30:22.011031Z] Running run-tests step. 18:30:22 INFO - Running pre-action listener: _resource_record_pre_action 18:30:22 INFO - Running main action method: run_tests 18:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:30:22 INFO - Python 2.7.3 18:30:22 INFO - Return code: 0 18:30:22 INFO - grabbing minidump binary from tooltool 18:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:22 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 0x12e2090>, '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 0x12e59b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12e5e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:30:22 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 18:30:22 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 18:30:22 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 18:30:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:30:22 INFO - Return code: 0 18:30:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:30:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:30:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:30:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:30:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 18:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 18:30:22 INFO - Using env: {'DISPLAY': ':0', 18:30:22 INFO - 'HOME': '/home/cltbld', 18:30:22 INFO - 'LANG': 'en_US.UTF-8', 18:30:22 INFO - 'LANGUAGE': 'en_US:en', 18:30:22 INFO - 'LOGNAME': 'cltbld', 18:30:22 INFO - 'MAIL': '/var/mail/cltbld', 18:30:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:30:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:22 INFO - 'MOZ_NO_REMOTE': '1', 18:30:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:30:22 INFO - 'NO_EM_RESTART': '1', 18:30:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:22 INFO - 'PWD': '/builds/slave/test', 18:30:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:30:22 INFO - 'SHELL': '/bin/bash', 18:30:22 INFO - 'SHLVL': '1', 18:30:22 INFO - 'TERM': 'linux', 18:30:22 INFO - 'TMOUT': '86400', 18:30:22 INFO - 'USER': 'cltbld', 18:30:22 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985', 18:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:22 INFO - '_': '/tools/buildbot/bin/python'} 18:30:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 18:30:22 INFO - mozversion application_buildid: 20160829174130 18:30:22 INFO - mozversion application_changeset: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f 18:30:22 INFO - mozversion application_display_name: Nightly 18:30:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:30:22 INFO - mozversion application_name: Firefox 18:30:22 INFO - mozversion application_remotingname: firefox 18:30:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:30:22 INFO - mozversion application_vendor: Mozilla 18:30:22 INFO - mozversion application_version: 51.0a1 18:30:22 INFO - mozversion platform_buildid: 20160829174130 18:30:22 INFO - mozversion platform_changeset: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f 18:30:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:30:22 INFO - mozversion platform_version: 51.0a1 18:30:22 INFO - using testdate: 1472520622 18:30:22 INFO - actual date: 1472520622 18:30:22 INFO - starting webserver on 'localhost:39628' 18:30:22 INFO - SUITE-START | Running 2 tests 18:30:22 INFO - TEST-START | dromaeo_css 18:30:22 INFO - operating with platform_type : linux_ 18:30:22 INFO - Initialising browser for dromaeo_css test... 18:30:22 INFO - TEST-INFO | started process 12928 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHu9UCt/profile http://localhost:39628/getInfo.html) 18:30:26 INFO - PROCESS | 12928 | __metrics Screen width/height:1600/1200 18:30:26 INFO - PROCESS | 12928 | colorDepth:24 18:30:26 INFO - PROCESS | 12928 | Browser inner width/height: 1024/697 18:30:26 INFO - PROCESS | 12928 | __metrics 18:30:27 INFO - PROCESS | 12928 | 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" 18:30:27 INFO - PROCESS | 12928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:28 INFO - TEST-INFO | 12928: exit 0 18:30:28 INFO - Browser initialized. 18:30:28 INFO - Running cycle 1/1 for dromaeo_css test... 18:30:28 INFO - TEST-INFO | started process 13012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHu9UCt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:40 INFO - PROCESS | 13012 | RSS: Main: 181510144 18:30:40 INFO - PROCESS | 13012 | 18:31:31 INFO - PROCESS | 13012 | 1472520691613 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] 18:33:44 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:39628/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39628/tests/dromaeo/cssquery-ext.html) 18:33:45 INFO - PROCESS | 13012 | RSS: Main: 267423744 18:33:45 INFO - PROCESS | 13012 | 18:36:48 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:39628/tests/dromaeo/cssquery-ext.html (next: http://localhost:39628/tests/dromaeo/cssquery-jquery.html) 18:36:49 INFO - PROCESS | 13012 | RSS: Main: 269795328 18:36:49 INFO - PROCESS | 13012 | 18:39:51 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:39628/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39628/tests/dromaeo/cssquery-mootools.html) 18:39:52 INFO - PROCESS | 13012 | RSS: Main: 245403648 18:39:52 INFO - PROCESS | 13012 | 18:42:54 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:39628/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39628/tests/dromaeo/cssquery-prototype.html) 18:42:54 INFO - PROCESS | 13012 | RSS: Main: 218378240 18:42:54 INFO - PROCESS | 13012 | 18:45:57 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:39628/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39628/tests/dromaeo/cssquery-yui.html) 18:45:58 INFO - PROCESS | 13012 | RSS: Main: 217661440 18:45:58 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:39628/tests/dromaeo/cssquery-yui.html (next: http://localhost:39628/tests/dromaeo/cssquery-dojo.html) 18:49:01 INFO - PROCESS | 13012 | RSS: Main: 308944896 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | __start_tp_report 18:49:01 INFO - PROCESS | 13012 | _x_x_mozilla_page_load 18:49:01 INFO - PROCESS | 13012 | _x_x_mozilla_page_load_details 18:49:01 INFO - PROCESS | 13012 | |i|pagename|runs| 18:49:01 INFO - PROCESS | 13012 | |0;dojo.html;2583.3556807008135;2597.5714007188817;2598.2854714953396;2640.9999999999986;2650.602409638554;4511.436070491189;4646.326282689008;4691.788869500872;4723.031778485407;4832.395950506187;4700.0599880023965;4735.147673418784;4785.71285722856;4793.3528973588645;4794.089123066615;4285.35719642053;4315.287977483711;4392.8023238954165;4452.042810795679;4522.186006518826;4358.956410435887;4431.091626216634;4439.622632076277;4476.306172543261;4476.552344765513;4284.635805956497;4316.6546676265825;4351.6953813233085;4356.4336636237085;4414.293713005904;4292.785360731951;4304.246756817575;4309;4316.6978311518205;4393;4272.9359059614135;4341.826326946918;4343.9348409773875;4354.216241076605;4408.40486534317;4616.122936642061;4735.83424580138;4738.763061846894;4743.312219728155;4750.667453278272;4980.676256043345;5031.018951304497;5040.6471546991725;5046.7224302663335;5116.079105760961;5025.396952365703;5052.21690637948;5071.39143302806;5073.974630126826;5081.59347252219;4603.125406172817;4655.44134703831;4657.836975705834;4717.9292310615365;4765.475797662254;5097.770600322994;5211.035958347642;5215.687058776485;5240.842774716764;5287.391949925731;5007.349044624176;5007.399112106497;5008.69947803133;5010.599152067826;5076.6192535559985;4881.4630390657;5085.847424577269;5106.208537676666;5110.233464980282;5110.616703747234;4958.677685950402;4989.351384320088;4992.775325110379;5014.398272207359;5074.162763144113;4982.177940639754;4989.25161225819;4997.825076122318;4999.875003124951;5069.112905241568;4194.559571244975;4266.274733295348;4269;4314.7626880521875;4330.047389574289;3681.5582130144553;3722.739408241397;3771.0195349208852;3818.4654148419245;3838.673712734393;4312.439382880206;4391.429114215131;4391.956080439155;4395.120975804852;4444.755538445416;5099.388073431212;5118.181091025418;5120.718360490135;5122.078025955364;5181.274621552986;4297.09760950204;4340;4345.760983145958;4382.6055654991205;4382.715123517025;4587.587117159472;4600.7929643166135;4615.930761038587;4641.883952901137;4663.114008338405;4766.547178018082;4774.7135171889795;4781;4785.545373189541;4837.7097374157665;4983.651144419856;4986.828095397651;5014.52362025607;5101.51535604117;5106.412762532208;4813.542713442217;4819.3252944588485;4820.373351464287;4826.276058591239;4875.3174555562955;5035.622328325464;5035.924461133012;5040.36995375578;5040.773165207501;5044.747762611928;4954.677945933503;5004.924926126184;5006.799728010985;5077.492250775041;5169.715665638276;4904.828331008398;4946.258061290835;4969.031038947515;4979.3526841510375;5041.369828771403;4963.255511673278;4965.4289756678445;4968.27959945813;4973.502649734997;5033.0688822567945;4780.378550788375;4902.460729319843;4902.656814022984;4916.139675556692;4959.231319145538;4888.1690112681645;4929.852104436873;4941.937483441077;4949.777260023379;5011.574016208663;4814.783334750015;4831.613470922248;4836.588889944394;4838.903221935471;4905.460399355997 18:49:01 INFO - PROCESS | 13012 | |1;ext.html;559.9944000559994;563.5097465205271;564.8672561947944;564.9915251271232;569.9373068962414;27793.3051673708;37284.813575932116;38480.422793658116;38490.615093849054;41938.58061419385;39141.21717565647;39162.60837391625;39343.40984885229;39609.40585891214;39621.80189099057;7602.619869006541;7777.688892444324;7784.805379865492;7786.80532986677;7799.103103143155;7310.122785265776;9850;10112.747181320452;10114.291999560033;10401.167906567494;10122.29143959923;10198.388096714183;10215;10221.28451008426;10230.795384092313;10026.047525485104;10103.242256830767;10104.191664666809;10110.898891011106;10114.190864730877;9906.950465247699;9955.900440995641;9998.550065247082;9999.100080992674;10009.499525023792;36337.27325453476;36583.63416365829;36625.45061824075;36626.63373366259;36648.08379790527;2318.8260880434036;2328.557574060923;2397.5804234258935;2800.089970759491;2816.662000559925;27637.585436218473;27707.64053445914;28113.85943070292;28134.577981330298;29008.099190081033;3337.632860385356;3352.9329413411597;3385.339858727549;3402.8638854445794;3457.3258214648154;243118;243719;244944.77527612427;245280;245602;51174.23238651423;51203.23195152076;51209;51267.743661281456;51433.74283128561;31275.37449251002;31911.84044079765;54281;54517.454825452034;54606.72696636491;12287.324197169062;12605.873941260654;12610.432530536147;12615.495380184873;12621.242725436503;8859.468431894107;9060.682876099305;9903.950480247553;9969.152622027064;9992.450415227087;15535.611609709622;15638.296276667577;17045.829541704672;17226.913865430593;17400.65198696044;6615.437687796495;6798.93201067983;6887.414569761627;6922.376986071177;6977.860442791217;698.8532408194336;735.988960165598;773.4006145237373;836.2933321343455;921.5023887101024;27012.324691882466;27361.452770944867;27362.83610275683;27390.452190956068;28081.719182807912;887.8224355129;889.217488610032;898.4564338575068;929.6142101027998;1028.840529717895;14202.437731849488;14637.341319640642;14718.926405367905;14730.410783568752;15066.397344106113;119170;119591.40204298923;120423;120472.39763801124;120819;47814.76092619584;47892;48329;48380.75809620929;48420.75789621029;1020.4897551224387;1063.330102035728;1080.68660088575;1083.2363183955135;1120.618989543559;24896.875515622305;28618.713812861606;28641.856790715912;28918.27704307325;29565.704342956295;40231;40755.18489630132;40799;40801.387979179744;40841.79579102086;274475;276254;276821;277058;277180;23725.64411533794;24109.879450602635;24917.377065573506;24995.62541558549;26216.475670486863;38102.61897381102;38934.22131557296;39015.21969560536;39105.804470978605;39619;29719.702802971693;30064.24839379033;30142;30424;30560.541591876583;30567;30879;31436.214094647818;31457.528137077505;32036;33460.83269583636;33996.49005264972;34282.14294642654;34419.4837077439;34721.826390867885;28998.565021525115;31256.531152033192;31459;31760.841195793873;31874.84062579672;24460.51078978446;24463.633045503975;24660.876695616407;24692.506149876543;24788.752112479367 18:49:01 INFO - PROCESS | 13012 | |2;jquery.html;336.7911894625333;339.65637103982516;348.907539502032;352.7883270037977;380.5604526771578;12291.385430728462;15684.52946411608;15905.681886362281;15991.84008159918;17086.316547338123;15780.605484862885;15940.362385504566;16342.428015019477;16536.586585335343;16636.58408539787;4301.580478442106;4850.151223535876;5037.3451451311275;5059.49405059493;5082.695038297714;5032.320636714052;5095.770690318945;5100.362454693163;5105.285260063575;5109.3613298337705;4800.895793967389;4839.830605928793;4951.851444456672;5083.313752643383;5095;4933.679310844784;4973.005398920194;4977.328060711794;4980.103581355354;4980.97509512452;4432.645388368923;4441.955580444186;4451.732896026248;4478.798454069574;4745.193317136095;11167.497462614201;11203;11214.943925280402;11322.830157547643;11378.71553211168;1689.565909996015;1693.9407120750839;1702.242502086572;1730.4635562975393;1752.97370539442;8082.838343233161;8419.07390187079;9291.163795258459;9679.806403871884;12874.87125128746;1638.755264576146;1649.5216387247683;1652.818189999099;1681.9074950877696;1687.2070127040395;64882;65691.014634781;66219.3378066218;66546.66726666385;67981.320186798;9192.678256260999;9634.566444510014;9757.46110342976;9869.753256168653;9985.452254900501;13054.282014489105;13091.672708182181;13105.803412948813;13138.474461021453;13153.079284449997;6256.343083976208;6278;6493.383128602775;6536.444402225864;6670.699818508178;5835.562332825055;5951.464368206882;6142.78500252489;6346.841328966812;6350.523710721714;4160.916781664349;4230.49234091911;4243;4271.444712187458;4323.805428755713;1733.54927718794;1774.5918438759159;1784.8661350398772;1801.5225965119255;1813.9909300453414;743.3867059675681;782.1982467970375;821.4578378270313;840.2857571064546;907.609727817045;15443.922780386027;15778.368865245491;16046.679066418606;16200.189990500663;16986.150692465573;763.3320844261272;765.6478020110811;801.3315658856112;820.323233332503;822.2476434062766;8599.26906212979;9033.277337812959;9069.274458043341;9317.767055823522;9378.484183369843;358202;360081;364001.8597489358;365050;368404;73668;74092.25907740854;74353.62823185958;74472;75168;775.1860546426229;872.7992561710715;905.7599736069947;922.4972390047441;924.1497822003772;14405.207713575854;14964.251787410369;14971.32629031718;15940.68118637644;15998.440054598033;38323.425148622235;39058.414123787596;39559;39646;39848.20303593854;47013.76493117512;47325.52674473211;49676.75161624169;50906;52180.21729673982;7060.576365418091;7140.393066589398;7228.421726261991;7287.380572651384;7301.269873012656;7716.112647045437;9739.318247722875;11472.885271147516;11499.310041397543;11646.825297620373;10029.69910902645;10106.696799095746;10111.64609238673;10275.486225688835;10671.092957098734;9865.802683946424;10000.699979000641;10035.14701250402;10444.843327349943;10448.373097614167;10068.33872411044;10119.190464762949;10238.795224095626;10484.580616775243;11060.557577697124;6784.457243420613;7065.858682826418;7135.5361901476235;7221.855562888818;7337.156227033746;9068.637254509746;9902.306838521234;9994.250431217542;10019.54912028975;10208.438535880598 18:49:01 INFO - PROCESS | 13012 | |3;mootools.html;1219.6219172056658;1284.2037936479385;1353.9390727417265;1369.5822774053909;1673.221951792417;7049.5417797843165;7338.633068346581;7371.189169191392;7591.2788285112965;7598.3541398981015;7382.630868456583;7480.3267705906455;7493.625318734055;7496.250374962501;7507.061617297838;2118.745750509937;2141.175647375756;2142.689310050042;2144.04589957469;2145.442185031895;2086.311517199328;2134.039682143042;2134.7331583552054;2136.594047131048;2149.935501934937;2089.101686274901;2114.365690292906;2122.596706625744;2130.2970019893396;2136.540643761591;2097.0458441409123;2098.2341445372404;2100.411884672294;2102.9369118926456;2103.3269353806786;2012.5773587546632;2072.0261477105737;2073.3572592496225;2074.1288658763206;2074.7614024387085;7324.08448943882;7436.256374362574;7492.02603661526;7542.094948606147;7549.773506794805;4362.738235705868;4550.544945505456;4558.680892337537;4563.201439748064;4581.885452863672;2760.2961244882426;2854.971450285512;2868.18256796814;2900.5649152627066;2901.7243361880587;909.0409617853162;921.4839689773748;936.1293996583175;936.3305236755753;941.331654525281;10030.59877604895;10118.08937195656;10130.240231982558;10131.746706332327;10138.645147419878;3675.5956844747056;3855.8072096395067;3967.484227050465;4008.0981779100052;4016.196760647882;7751.534907905431;7816.53100813953;7843.098043724931;7852.450328477066;7854.8429031419055;4352.238358287351;4353.782310884443;4356.1287742451;4359.411479450297;4361.193179261847;3503.9474407883904;3897.376419772823;4020.8994775130855;4075.49056367954;4088.4276201331836;3364.512145738854;3380.3577320309064;3402.8128452935325;3429.8456569454434;3485.6165821759587;1009.6112996496402;1011.9797604048025;1023.3041531758475;1023.6263763726187;1051.7002654243504;562.60898675421;595.3808039638814;627.1815281058172;627.3632263252802;634.9904751428733;4462.553744625577;4571.3600095986585;4602.263637817933;4669.509701481364;4670.159371313196;381.4259539393195;381.5573934236273;381.7003652133062;385.60668118518953;385.857232823857;2953.9409211816073;3101.255698632312;3128.562001319817;3128.7340576051292;3173.841307934594;8854.690085846965;9294.628214871329;9294.953525232331;9305.53472326381;9312.115349041831;9228.630854765868;9232.215261702697;9235.399699019543;9251.953740231256;9252.398594091363;6854.246032936373;6929.44564434844;6947.89578156328;6957;6965.33829286217;4541.772911354353;4552.11233809404;4609.585137337655;4645.279981602857;4699.6240300776535;8233.711820086268;8265.46274492156;8399;8422.368322375723;8473.364497662576;8396.622152003052;8559.786005349915;8560.400771945904;8649.956750216208;8709.346798990227;5694.658320500783;5722.914156287576;5740.598158128891;5781.797637082682;5789.884202316014;6631.834204144935;6671.299513551007;6675.499337549801;6680.3987641112535;6699.39705426514;5703.572232082527;5775.624584402;5843.328017277898;5887.499562537061;5987.6108052976415;5656.406077361818;5698.088305871041;5769.59612827098;5987.880242395215;6009.699515024318;5379.220013098157;5562.9443705564045;5564.276644036249;5602.1036634138345;5628.577856660685;4068.898277543085;4102.897427564335;4113.794310284414;4116.4648595682365;4206.621404073648;4222.155568886296;4265.76538290397;4267.381229721611;4376.54046325144;4436.645068394456 18:49:01 INFO - PROCESS | 13012 | |4;prototype.html;269.18973888595326;270.2918353912748;270.6522119076986;274.88317465077336;275.10590580613007;4866.245731911555;4890.8777280568;4893.461719210893;4911.2633105034265;4948.752562371885;4833.081714474248;4843.903121937559;4888.926666100003;4901.852944411665;4909.729964851932;3530.7351948603828;3626.854925802978;3645.4531820227044;3664.633536646341;3679.3009328227686;3620.6379362063844;3757.530308711411;3769.962300377002;3784.735068545203;3816.3893776995687;3711.480392745018;3720.386136287509;3730.8134593270365;3742.663160315571;3766.2467506498674;3563.768518886279;3691.280200360931;3724.29238444696;3729.160938788778;3731.8507259709713;3185.7451403887635;3199.7440204783793;3213.586022150246;3218.5172224166326;3237.676232376767;4602.309653551961;4614.561616646413;4621.953780462186;4629.4722741495225;4655.393889108253;4185.58144185579;4217.240896638604;4219.1139860629;4220.409142720022;4225.936610950838;4818.132736107499;4981.875453113665;4983.775730092155;5017.197248440269;5058.873528161788;3513.1217195701074;3519.771214871025;3523.8195204606486;3524.524189234472;3527.400341941876;5195.532402083831;5195.636305458619;5198.818041368534;5220.817271395483;5302.549283310885;3553.34263161316;3613.5483064616924;3650.872219472306;3652.470391793175;3658.762180458262;3665.376885929398;3681.4661874028116;3694.6674799268194;3695.2055308108884;3695.464157697119;4236.512801027921;4243.936340954888;4247.384129301234;4254.234237837219;4270.7864606769535;4974.1543937530705;4992.154471950953;5032.370953630796;5039.622028347889;5052.242163675404;1225.6261840138843;1273.0642977411594;1276.4894042382934;1277.431542963391;1280.116719463567;783.824263604593;793.4009822583912;803.1526739289989;803.6303300481844;808.2442915873677;3996.800159991989;4308.116836048603;4405.757683327448;4460.30865215892;4479.148961697332;5158.767855446514;5184.222366645034;5188.898496109082;5196.090684130262;5197.506236907488;4499.280115181556;4501.662375321864;4503.707259028152;4509.774511274423;4520.593146616755;2637.2483842104907;2730.945381092407;2749.2164733050986;2757.379589592366;2772.4039331543813;4880.585150262196;4884.902301954012;4887.902241955141;4900.828471003569;4903.338049363361;5275.868103297448;5303.390110137384;5324.201369794562;5332.760025798849;5333.386660534011;4647.744374059459;4890.853274401774;4920.286558448934;4928.137575924199;4929.482404347564;5246.842594722164;5248.947510524846;5254.605904557097;5262.553027766819;5268.602785732559;4868.683535570177;5107.41264754558;5128.564072053917;5151.793928242828;5169.948300517097;4847;4901.2893130496595;4905.3623029006;4940.827071052496;4950.529699678525;3483.89548313551;3536.7170626350344;3543.911402214965;3556.16430138912;3579.19468119671;5130.640855140105;5255.421903590525;5274.813167037386;5285.127953887565;5299.49654782795;5021.949780502295;5057.6459647824295;5062.139436295912;5062.215356619729;5086.21163719624;5033.5721463676;5037.5214354636255;5044.1424957758;5052;5078.542931136216;4792.107117688423;4985.158459678094;4994.600431965361;5040.328625828317;5044.520770526794;4834.896819883362;4948.233023881304;5079.974600126976;5082.695038297714;5103.92344114846;4938.43208031081;4963.726995015309;4980.701157930535;4983.9501604984935;4997.325361076353 18:49:01 INFO - PROCESS | 13012 | |5;yui.html;545.4500211511184;558.17947617003;568.442925932586;576.9826905192845;587.4008511318453;11310.09519238461;12109.878901210985;12192.329421881794;12195.51217951282;12235.571754988567;11859.881401186007;12312.876871231285;12383.876161238384;12443.875561244386;12466.750664986695;729.1396152540001;752.4018405367746;756.0247281007494;757.0650246945019;758.1773775453639;609.3540846702488;738.8780851159579;757.1482082657011;757.1822431773671;770.818857568471;797.2585495489193;801.5591424716412;802.4663598706853;805.556943680976;805.8670319397322;802.0535767794;803.0363563723555;803.0443771911407;803.1526739290047;803.1687203744096;473.41296791978044;567.8608740858475;580.8431723434692;582.3245035758542;589.8731772668854;11858.644240672707;11903.761924761457;11914.285142891455;11966.64100076999;11999.040076793835;11304.491297891615;12310.384480775925;12313.13808033438;12642.999999999907;12822.743545129139;10843.67468975932;10846.945765271123;10925.180611454172;11129.332240065622;11233.382163980978;3965.8810235692977;3970.8411663533425;4058.7564746115327;4084.060666046796;4085.7344272622186;62955.68522157406;63717.36282637161;64844.351556484304;64975.3502464974;65012.67493662549;9074.773130671787;9372.67195648149;9428.10433008863;9475.90524094764;9597.520123993772;17409;17605;17631.294748210184;17634.206460709298;17655.73516397255;6231.158793562901;6325.399087086811;6333.936660633335;6544.3782840630065;6631.767888123893;3578.499010138583;3626.3472574936764;3643.6720695137033;3651.4522821576443;3692.8552148833946;2668.2662267876103;2764.626775385297;2775.653043369579;2777.680566734811;2778.305423644089;466.1562571745152;469.2000139761698;471.86080106368706;474.80532981477427;479.9640026997989;420.268732405612;429.44387018810716;430.78891343242213;447.764170888344;489.75757000284375;7126.429885609139;7435.293647103625;7934.246246606564;8055;8110.837783244302;394.16043826649053;418.09273875689826;423.927932251518;438.9517153113155;449.9617532509708;3614.331348700499;3666.4683620874825;3734.1971476132217;3764.6800021997888;3838.9040273992814;76694.616526917;78140.21859781329;78900.60549697214;103675;106709.46645266724;75285;79338;80471.59764201258;81261;81404;43679;49802.501974980514;68913.65543172252;70631;71147.28852711614;7765.844683106193;8115.188481151838;8141.755747327589;8180.754577362689;8190.426670133033;44348.77825610981;45496.77251613721;46436;47026;47029.529704702516;35718;51456.48543514517;53923.73038134784;54541;57550;9227.261819054325;9309.301802364986;9369.203617692572;9406.482643454401;9739.951300243454;11247.606333778278;11413.429328533706;11444.484998174936;11719.824202637137;11875.465604048011;10929;11027.83458248111;11113.166512511432;11448.484818183035;11589.536418543501;11105.389203593557;11448.3703396314;11782.646520604396;11806.881931180578;11909.880901190878;10733.785324293314;10785.191110666889;11096.889031109586;11205.775884482428;11442.713932151762;9335.113164249386;9427.764305892408;9452.243820494481;9544.95227523858;9602.423854568748;9361.4383137012;9423.670171543963;9489.525523723923;9726.270529710158;9879.654212102541 18:49:01 INFO - PROCESS | 13012 | __end_tp_report 18:49:01 INFO - PROCESS | 13012 | __start_cc_report 18:49:01 INFO - PROCESS | 13012 | _x_x_mozilla_cycle_collect,1195 18:49:01 INFO - PROCESS | 13012 | __end_cc_report 18:49:01 INFO - PROCESS | 13012 | __startTimestamp1472521741925__endTimestamp 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | ------- Summary: start ------- 18:49:01 INFO - PROCESS | 13012 | Number of tests: 6 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | [#0] dojo.html Cycles:165 Average:4685.00 Median:4820.37 stddev:494.42 (10.3%) stddev-sans-first:467.64 18:49:01 INFO - PROCESS | 13012 | Values: 2583.4 2597.6 2598.3 2641.0 2650.6 4511.4 4646.3 4691.8 4723.0 4832.4 4700.1 4735.1 4785.7 4793.4 4794.1 4285.4 4315.3 4392.8 4452.0 4522.2 4359.0 4431.1 4439.6 4476.3 4476.6 4284.6 4316.7 4351.7 4356.4 4414.3 4292.8 4304.2 4309.0 4316.7 4393.0 4272.9 4341.8 4343.9 4354.2 4408.4 4616.1 4735.8 4738.8 4743.3 4750.7 4980.7 5031.0 5040.6 5046.7 5116.1 5025.4 5052.2 5071.4 5074.0 5081.6 4603.1 4655.4 4657.8 4717.9 4765.5 5097.8 5211.0 5215.7 5240.8 5287.4 5007.3 5007.4 5008.7 5010.6 5076.6 4881.5 5085.8 5106.2 5110.2 5110.6 4958.7 4989.4 4992.8 5014.4 5074.2 4982.2 4989.3 4997.8 4999.9 5069.1 4194.6 4266.3 4269.0 4314.8 4330.0 3681.6 3722.7 3771.0 3818.5 3838.7 4312.4 4391.4 4392.0 4395.1 4444.8 5099.4 5118.2 5120.7 5122.1 5181.3 4297.1 4340.0 4345.8 4382.6 4382.7 4587.6 4600.8 4615.9 4641.9 4663.1 4766.5 4774.7 4781.0 4785.5 4837.7 4983.7 4986.8 5014.5 5101.5 5106.4 4813.5 4819.3 4820.4 4826.3 4875.3 5035.6 5035.9 5040.4 5040.8 5044.7 4954.7 5004.9 5006.8 5077.5 5169.7 4904.8 4946.3 4969.0 4979.4 5041.4 4963.3 4965.4 4968.3 4973.5 5033.1 4780.4 4902.5 4902.7 4916.1 4959.2 4888.2 4929.9 4941.9 4949.8 5011.6 4814.8 4831.6 4836.6 4838.9 4905.5 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | [#1] ext.html Cycles:180 Average:37637.30 Median:31355.79 stddev:58618.06 (186.9%) stddev-sans-first:58716.39 18:49:01 INFO - PROCESS | 13012 | Values: 560.0 563.5 564.9 565.0 569.9 27793.3 37284.8 38480.4 38490.6 41938.6 39141.2 39162.6 39343.4 39609.4 39621.8 7602.6 7777.7 7784.8 7786.8 7799.1 7310.1 9850.0 10112.7 10114.3 10401.2 10122.3 10198.4 10215.0 10221.3 10230.8 10026.0 10103.2 10104.2 10110.9 10114.2 9907.0 9955.9 9998.6 9999.1 10009.5 36337.3 36583.6 36625.5 36626.6 36648.1 2318.8 2328.6 2397.6 2800.1 2816.7 27637.6 27707.6 28113.9 28134.6 29008.1 3337.6 3352.9 3385.3 3402.9 3457.3 243118.0 243719.0 244944.8 245280.0 245602.0 51174.2 51203.2 51209.0 51267.7 51433.7 31275.4 31911.8 54281.0 54517.5 54606.7 12287.3 12605.9 12610.4 12615.5 12621.2 8859.5 9060.7 9904.0 9969.2 9992.5 15535.6 15638.3 17045.8 17226.9 17400.7 6615.4 6798.9 6887.4 6922.4 6977.9 698.9 736.0 773.4 836.3 921.5 27012.3 27361.5 27362.8 27390.5 28081.7 887.8 889.2 898.5 929.6 1028.8 14202.4 14637.3 14718.9 14730.4 15066.4 119170.0 119591.4 120423.0 120472.4 120819.0 47814.8 47892.0 48329.0 48380.8 48420.8 1020.5 1063.3 1080.7 1083.2 1120.6 24896.9 28618.7 28641.9 28918.3 29565.7 40231.0 40755.2 40799.0 40801.4 40841.8 274475.0 276254.0 276821.0 277058.0 277180.0 23725.6 24109.9 24917.4 24995.6 26216.5 38102.6 38934.2 39015.2 39105.8 39619.0 29719.7 30064.2 30142.0 30424.0 30560.5 30567.0 30879.0 31436.2 31457.5 32036.0 33460.8 33996.5 34282.1 34419.5 34721.8 28998.6 31256.5 31459.0 31760.8 31874.8 24460.5 24463.6 24660.9 24692.5 24788.8 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | [#2] jquery.html Cycles:180 Average:22856.31 Median:47169.65 stddev:60124.30 (127.5%) stddev-sans-first:60269.19 18:49:01 INFO - PROCESS | 13012 | Values: 336.8 339.7 348.9 352.8 380.6 12291.4 15684.5 15905.7 15991.8 17086.3 15780.6 15940.4 16342.4 16536.6 16636.6 4301.6 4850.2 5037.3 5059.5 5082.7 5032.3 5095.8 5100.4 5105.3 5109.4 4800.9 4839.8 4951.9 5083.3 5095.0 4933.7 4973.0 4977.3 4980.1 4981.0 4432.6 4442.0 4451.7 4478.8 4745.2 11167.5 11203.0 11214.9 11322.8 11378.7 1689.6 1693.9 1702.2 1730.5 1753.0 8082.8 8419.1 9291.2 9679.8 12874.9 1638.8 1649.5 1652.8 1681.9 1687.2 64882.0 65691.0 66219.3 66546.7 67981.3 9192.7 9634.6 9757.5 9869.8 9985.5 13054.3 13091.7 13105.8 13138.5 13153.1 6256.3 6278.0 6493.4 6536.4 6670.7 5835.6 5951.5 6142.8 6346.8 6350.5 4160.9 4230.5 4243.0 4271.4 4323.8 1733.5 1774.6 1784.9 1801.5 1814.0 743.4 782.2 821.5 840.3 907.6 15443.9 15778.4 16046.7 16200.2 16986.2 763.3 765.6 801.3 820.3 822.2 8599.3 9033.3 9069.3 9317.8 9378.5 358202.0 360081.0 364001.9 365050.0 368404.0 73668.0 74092.3 74353.6 74472.0 75168.0 775.2 872.8 905.8 922.5 924.1 14405.2 14964.3 14971.3 15940.7 15998.4 38323.4 39058.4 39559.0 39646.0 39848.2 47013.8 47325.5 49676.8 50906.0 52180.2 7060.6 7140.4 7228.4 7287.4 7301.3 7716.1 9739.3 11472.9 11499.3 11646.8 10029.7 10106.7 10111.6 10275.5 10671.1 9865.8 10000.7 10035.1 10444.8 10448.4 10068.3 10119.2 10238.8 10484.6 11060.6 6784.5 7065.9 7135.5 7221.9 7337.2 9068.6 9902.3 9994.3 10019.5 10208.4 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | [#3] mootools.html Cycles:180 Average:4744.23 Median:4560.94 stddev:2703.34 (59.3%) stddev-sans-first:2697.95 18:49:01 INFO - PROCESS | 13012 | Values: 1219.6 1284.2 1353.9 1369.6 1673.2 7049.5 7338.6 7371.2 7591.3 7598.4 7382.6 7480.3 7493.6 7496.3 7507.1 2118.7 2141.2 2142.7 2144.0 2145.4 2086.3 2134.0 2134.7 2136.6 2149.9 2089.1 2114.4 2122.6 2130.3 2136.5 2097.0 2098.2 2100.4 2102.9 2103.3 2012.6 2072.0 2073.4 2074.1 2074.8 7324.1 7436.3 7492.0 7542.1 7549.8 4362.7 4550.5 4558.7 4563.2 4581.9 2760.3 2855.0 2868.2 2900.6 2901.7 909.0 921.5 936.1 936.3 941.3 10030.6 10118.1 10130.2 10131.7 10138.6 3675.6 3855.8 3967.5 4008.1 4016.2 7751.5 7816.5 7843.1 7852.5 7854.8 4352.2 4353.8 4356.1 4359.4 4361.2 3503.9 3897.4 4020.9 4075.5 4088.4 3364.5 3380.4 3402.8 3429.8 3485.6 1009.6 1012.0 1023.3 1023.6 1051.7 562.6 595.4 627.2 627.4 635.0 4462.6 4571.4 4602.3 4669.5 4670.2 381.4 381.6 381.7 385.6 385.9 2953.9 3101.3 3128.6 3128.7 3173.8 8854.7 9294.6 9295.0 9305.5 9312.1 9228.6 9232.2 9235.4 9252.0 9252.4 6854.2 6929.4 6947.9 6957.0 6965.3 4541.8 4552.1 4609.6 4645.3 4699.6 8233.7 8265.5 8399.0 8422.4 8473.4 8396.6 8559.8 8560.4 8650.0 8709.3 5694.7 5722.9 5740.6 5781.8 5789.9 6631.8 6671.3 6675.5 6680.4 6699.4 5703.6 5775.6 5843.3 5887.5 5987.6 5656.4 5698.1 5769.6 5987.9 6009.7 5379.2 5562.9 5564.3 5602.1 5628.6 4068.9 4102.9 4113.8 4116.5 4206.6 4222.2 4265.8 4267.4 4376.5 4436.6 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | [#4] prototype.html Cycles:180 Average:4171.79 Median:4845.45 stddev:1237.41 (25.5%) stddev-sans-first:1205.71 18:49:01 INFO - PROCESS | 13012 | Values: 269.2 270.3 270.7 274.9 275.1 4866.2 4890.9 4893.5 4911.3 4948.8 4833.1 4843.9 4888.9 4901.9 4909.7 3530.7 3626.9 3645.5 3664.6 3679.3 3620.6 3757.5 3770.0 3784.7 3816.4 3711.5 3720.4 3730.8 3742.7 3766.2 3563.8 3691.3 3724.3 3729.2 3731.9 3185.7 3199.7 3213.6 3218.5 3237.7 4602.3 4614.6 4622.0 4629.5 4655.4 4185.6 4217.2 4219.1 4220.4 4225.9 4818.1 4981.9 4983.8 5017.2 5058.9 3513.1 3519.8 3523.8 3524.5 3527.4 5195.5 5195.6 5198.8 5220.8 5302.5 3553.3 3613.5 3650.9 3652.5 3658.8 3665.4 3681.5 3694.7 3695.2 3695.5 4236.5 4243.9 4247.4 4254.2 4270.8 4974.2 4992.2 5032.4 5039.6 5052.2 1225.6 1273.1 1276.5 1277.4 1280.1 783.8 793.4 803.2 803.6 808.2 3996.8 4308.1 4405.8 4460.3 4479.1 5158.8 5184.2 5188.9 5196.1 5197.5 4499.3 4501.7 4503.7 4509.8 4520.6 2637.2 2730.9 2749.2 2757.4 2772.4 4880.6 4884.9 4887.9 4900.8 4903.3 5275.9 5303.4 5324.2 5332.8 5333.4 4647.7 4890.9 4920.3 4928.1 4929.5 5246.8 5248.9 5254.6 5262.6 5268.6 4868.7 5107.4 5128.6 5151.8 5169.9 4847.0 4901.3 4905.4 4940.8 4950.5 3483.9 3536.7 3543.9 3556.2 3579.2 5130.6 5255.4 5274.8 5285.1 5299.5 5021.9 5057.6 5062.1 5062.2 5086.2 5033.6 5037.5 5044.1 5052.0 5078.5 4792.1 4985.2 4994.6 5040.3 5044.5 4834.9 4948.2 5080.0 5082.7 5103.9 4938.4 4963.7 4980.7 4984.0 4997.3 18:49:01 INFO - PROCESS | 13012 | 18:49:01 INFO - PROCESS | 13012 | [#5] yui.html Cycles:180 Average:16458.49 Median:472.64 stddev:23330.28 (4936.2%) stddev-sans-first:23365.13 18:49:01 INFO - PROCESS | 13012 | Values: 545.5 558.2 568.4 577.0 587.4 11310.1 12109.9 12192.3 12195.5 12235.6 11859.9 12312.9 12383.9 12443.9 12466.8 729.1 752.4 756.0 757.1 758.2 609.4 738.9 757.1 757.2 770.8 797.3 801.6 802.5 805.6 805.9 802.1 803.0 803.0 803.2 803.2 473.4 567.9 580.8 582.3 589.9 11858.6 11903.8 11914.3 11966.6 11999.0 11304.5 12310.4 12313.1 12643.0 12822.7 10843.7 10846.9 10925.2 11129.3 11233.4 3965.9 3970.8 4058.8 4084.1 4085.7 62955.7 63717.4 64844.4 64975.4 65012.7 9074.8 9372.7 9428.1 9475.9 9597.5 17409.0 17605.0 17631.3 17634.2 17655.7 6231.2 6325.4 6333.9 6544.4 6631.8 3578.5 3626.3 3643.7 3651.5 3692.9 2668.3 2764.6 2775.7 2777.7 2778.3 466.2 469.2 471.9 474.8 480.0 420.3 429.4 430.8 447.8 489.8 7126.4 7435.3 7934.2 8055.0 8110.8 394.2 418.1 423.9 439.0 450.0 3614.3 3666.5 3734.2 3764.7 3838.9 76694.6 78140.2 78900.6 103675.0 106709.5 75285.0 79338.0 80471.6 81261.0 81404.0 43679.0 49802.5 68913.7 70631.0 71147.3 7765.8 8115.2 8141.8 8180.8 8190.4 44348.8 45496.8 46436.0 47026.0 47029.5 35718.0 51456.5 53923.7 54541.0 57550.0 9227.3 9309.3 9369.2 9406.5 9740.0 11247.6 11413.4 11444.5 11719.8 11875.5 10929.0 11027.8 11113.2 11448.5 11589.5 11105.4 11448.4 11782.6 11806.9 11909.9 10733.8 10785.2 11096.9 11205.8 11442.7 9335.1 9427.8 9452.2 9545.0 9602.4 9361.4 9423.7 9489.5 9726.3 9879.7 18:49:01 INFO - PROCESS | 13012 | -------- Summary: end -------- 18:49:01 INFO - PROCESS | 13012 | 18:49:02 INFO - TEST-INFO | 13012: exit 0 18:49:02 INFO - TEST-OK | dromaeo_css | took 1120626ms 18:49:02 INFO - TEST-START | kraken 18:49:02 INFO - operating with platform_type : linux_ 18:49:02 INFO - Initialising browser for kraken test... 18:49:02 INFO - TEST-INFO | started process 13323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOvuMi8/profile http://localhost:39628/getInfo.html) 18:49:07 INFO - PROCESS | 13323 | __metrics Screen width/height:1600/1200 18:49:07 INFO - PROCESS | 13323 | colorDepth:24 18:49:07 INFO - PROCESS | 13323 | Browser inner width/height: 1024/697 18:49:07 INFO - PROCESS | 13323 | __metrics 18:49:07 INFO - PROCESS | 13323 | 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" 18:49:07 INFO - PROCESS | 13323 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:08 INFO - TEST-INFO | 13323: exit 0 18:49:08 INFO - Browser initialized. 18:49:08 INFO - Running cycle 1/1 for kraken test... 18:49:08 INFO - TEST-INFO | started process 13407 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOvuMi8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:20 INFO - PROCESS | 13407 | RSS: Main: 182247424 18:49:20 INFO - PROCESS | 13407 | 18:49:23 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39628/tests/kraken/driver.html?testName=audio-beat-detection) 18:49:24 INFO - PROCESS | 13407 | RSS: Main: 235544576 18:49:24 INFO - PROCESS | 13407 | 18:49:27 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39628/tests/kraken/driver.html?testName=audio-dft) 18:49:28 INFO - PROCESS | 13407 | RSS: Main: 225910784 18:49:28 INFO - PROCESS | 13407 | 18:49:31 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39628/tests/kraken/driver.html?testName=audio-fft) 18:49:31 INFO - PROCESS | 13407 | RSS: Main: 305840128 18:49:31 INFO - PROCESS | 13407 | 18:49:34 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39628/tests/kraken/driver.html?testName=audio-oscillator) 18:49:34 INFO - PROCESS | 13407 | RSS: Main: 214749184 18:49:34 INFO - PROCESS | 13407 | 18:49:36 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39628/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:49:37 INFO - PROCESS | 13407 | RSS: Main: 216772608 18:49:37 INFO - PROCESS | 13407 | 18:49:40 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39628/tests/kraken/driver.html?testName=imaging-darkroom) 18:49:41 INFO - PROCESS | 13407 | RSS: Main: 255180800 18:49:41 INFO - PROCESS | 13407 | 18:49:44 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39628/tests/kraken/driver.html?testName=imaging-desaturate) 18:49:45 INFO - PROCESS | 13407 | RSS: Main: 249565184 18:49:45 INFO - PROCESS | 13407 | 18:49:48 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39628/tests/kraken/driver.html?testName=json-parse-financial) 18:49:48 INFO - PROCESS | 13407 | RSS: Main: 252706816 18:49:48 INFO - PROCESS | 13407 | 18:49:50 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39628/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:49:51 INFO - PROCESS | 13407 | RSS: Main: 216461312 18:49:51 INFO - PROCESS | 13407 | 18:49:53 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:49:53 INFO - PROCESS | 13407 | RSS: Main: 224858112 18:49:53 INFO - PROCESS | 13407 | 18:49:55 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:49:56 INFO - PROCESS | 13407 | RSS: Main: 223059968 18:49:56 INFO - PROCESS | 13407 | 18:49:59 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:00 INFO - PROCESS | 13407 | RSS: Main: 224452608 18:50:00 INFO - PROCESS | 13407 | 18:50:03 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:03 INFO - PROCESS | 13407 | RSS: Main: 221949952 18:50:03 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | Cycle 1(1): loaded http://localhost:39628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39628/tests/kraken/driver.html?testName=ai-astar) 18:50:05 INFO - PROCESS | 13407 | RSS: Main: 272752640 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | __start_tp_report 18:50:05 INFO - PROCESS | 13407 | _x_x_mozilla_page_load 18:50:05 INFO - PROCESS | 13407 | _x_x_mozilla_page_load_details 18:50:05 INFO - PROCESS | 13407 | |i|pagename|runs| 18:50:05 INFO - PROCESS | 13407 | |0;ai-astar;115;117;135;115;115;116;115;115;114;117 18:50:05 INFO - PROCESS | 13407 | |1;audio-beat-detection;164;129;165;193;125;151;156;126;194;125 18:50:05 INFO - PROCESS | 13407 | |2;audio-dft;162;163;163;164;162;162;162;163;163;164 18:50:05 INFO - PROCESS | 13407 | |3;audio-fft;76;77;76;174;77;75;77;78;159;79 18:50:05 INFO - PROCESS | 13407 | |4;audio-oscillator;85;85;139;83;83;84;143;83;83;144 18:50:05 INFO - PROCESS | 13407 | |5;imaging-gaussian-blur;118;117;118;118;118;118;118;117;119;119 18:50:05 INFO - PROCESS | 13407 | |6;imaging-darkroom;147;148;147;148;149;148;147;148;147;148 18:50:05 INFO - PROCESS | 13407 | |7;imaging-desaturate;95;97;96;96;96;96;96;95;96;96 18:50:05 INFO - PROCESS | 13407 | |8;json-parse-financial;68;75;67;64;66;66;66;66;67;65 18:50:05 INFO - PROCESS | 13407 | |9;json-stringify-tinderbox;66;67;64;66;67;66;67;66;66;66 18:50:05 INFO - PROCESS | 13407 | |10;stanford-crypto-aes;90;82;83;81;88;83;82;83;91;82 18:50:05 INFO - PROCESS | 13407 | |11;stanford-crypto-ccm;144;140;141;134;144;135;143;141;183;134 18:50:05 INFO - PROCESS | 13407 | |12;stanford-crypto-pbkdf2;191;177;173;178;177;178;176;173;175;174 18:50:05 INFO - PROCESS | 13407 | |13;stanford-crypto-sha256-iterative;70;69;71;68;72;70;69;69;69;68 18:50:05 INFO - PROCESS | 13407 | __end_tp_report 18:50:05 INFO - PROCESS | 13407 | __start_cc_report 18:50:05 INFO - PROCESS | 13407 | _x_x_mozilla_cycle_collect,2001 18:50:05 INFO - PROCESS | 13407 | __end_cc_report 18:50:05 INFO - PROCESS | 13407 | __startTimestamp1472521805869__endTimestamp 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | ------- Summary: start ------- 18:50:05 INFO - PROCESS | 13407 | Number of tests: 14 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#0] ai-astar Cycles:10 Average:117.40 Median:115.50 stddev:6.26 (5.4%) stddev-sans-first:6.58 18:50:05 INFO - PROCESS | 13407 | Values: 115.0 117.0 135.0 115.0 115.0 116.0 115.0 115.0 114.0 117.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#1] audio-beat-detection Cycles:10 Average:152.80 Median:160.00 stddev:26.73 (16.7%) stddev-sans-first:28.05 18:50:05 INFO - PROCESS | 13407 | Values: 164.0 129.0 165.0 193.0 125.0 151.0 156.0 126.0 194.0 125.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 18:50:05 INFO - PROCESS | 13407 | Values: 162.0 163.0 163.0 164.0 162.0 162.0 162.0 163.0 163.0 164.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#3] audio-fft Cycles:10 Average:94.80 Median:77.00 stddev:37.97 (49.3%) stddev-sans-first:39.66 18:50:05 INFO - PROCESS | 13407 | Values: 76.0 77.0 76.0 174.0 77.0 75.0 77.0 78.0 159.0 79.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:83.00 stddev:28.19 (34.0%) stddev-sans-first:29.29 18:50:05 INFO - PROCESS | 13407 | Values: 85.0 85.0 139.0 83.0 83.0 84.0 143.0 83.0 83.0 144.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 18:50:05 INFO - PROCESS | 13407 | Values: 118.0 117.0 118.0 118.0 118.0 118.0 118.0 117.0 119.0 119.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 18:50:05 INFO - PROCESS | 13407 | Values: 147.0 148.0 147.0 148.0 149.0 148.0 147.0 148.0 147.0 148.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 18:50:05 INFO - PROCESS | 13407 | Values: 95.0 97.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#8] json-parse-financial Cycles:10 Average:67.00 Median:66.50 stddev:3.02 (4.5%) stddev-sans-first:3.18 18:50:05 INFO - PROCESS | 13407 | Values: 68.0 75.0 67.0 64.0 66.0 66.0 66.0 66.0 67.0 65.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 18:50:05 INFO - PROCESS | 13407 | Values: 66.0 67.0 64.0 66.0 67.0 66.0 67.0 66.0 66.0 66.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:83.00 stddev:3.69 (4.4%) stddev-sans-first:3.33 18:50:05 INFO - PROCESS | 13407 | Values: 90.0 82.0 83.0 81.0 88.0 83.0 82.0 83.0 91.0 82.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:142.00 stddev:14.29 (10.1%) stddev-sans-first:15.15 18:50:05 INFO - PROCESS | 13407 | Values: 144.0 140.0 141.0 134.0 144.0 135.0 143.0 141.0 183.0 134.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:177.00 stddev:5.20 (2.9%) stddev-sans-first:2.00 18:50:05 INFO - PROCESS | 13407 | Values: 191.0 177.0 173.0 178.0 177.0 178.0 176.0 173.0 175.0 174.0 18:50:05 INFO - PROCESS | 13407 | 18:50:05 INFO - PROCESS | 13407 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:1.27 (1.8%) stddev-sans-first:1.33 18:50:05 INFO - PROCESS | 13407 | Values: 70.0 69.0 71.0 68.0 72.0 70.0 69.0 69.0 69.0 68.0 18:50:05 INFO - PROCESS | 13407 | -------- Summary: end -------- 18:50:05 INFO - PROCESS | 13407 | 18:50:07 INFO - TEST-INFO | 13407: exit 0 18:50:07 INFO - TEST-OK | kraken | took 64268ms 18:50:07 INFO - SUITE-END | took 1184s 18:50:07 INFO - Completed test suite (00:19:45) 18:50:07 INFO - javascript benchmark 18:50:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2583.3556807008135, 2597.5714007188817, 2598.2854714953396, 2640.9999999999986, 2650.602409638554, 4511.436070491189, 4646.326282689008, 4691.788869500872, 4723.031778485407, 4832.395950506187, 4700.0599880023965, 4735.147673418784, 4785.71285722856, 4793.3528973588645, 4794.089123066615, 4285.35719642053, 4315.287977483711, 4392.8023238954165, 4452.042810795679, 4522.186006518826, 4358.956410435887, 4431.091626216634, 4439.622632076277, 4476.306172543261, 4476.552344765513, 4284.635805956497, 4316.6546676265825, 4351.6953813233085, 4356.4336636237085, 4414.293713005904, 4292.785360731951, 4304.246756817575, 4309.0, 4316.6978311518205, 4393.0, 4272.9359059614135, 4341.826326946918, 4343.9348409773875, 4354.216241076605, 4408.40486534317, 4616.122936642061, 4735.83424580138, 4738.763061846894, 4743.312219728155, 4750.667453278272, 4980.676256043345, 5031.018951304497, 5040.6471546991725, 5046.7224302663335, 5116.079105760961, 5025.396952365703, 5052.21690637948, 5071.39143302806, 5073.974630126826, 5081.59347252219, 4603.125406172817, 4655.44134703831, 4657.836975705834, 4717.9292310615365, 4765.475797662254, 5097.770600322994, 5211.035958347642, 5215.687058776485, 5240.842774716764, 5287.391949925731, 5007.349044624176, 5007.399112106497, 5008.69947803133, 5010.599152067826, 5076.6192535559985, 4881.4630390657, 5085.847424577269, 5106.208537676666, 5110.233464980282, 5110.616703747234, 4958.677685950402, 4989.351384320088, 4992.775325110379, 5014.398272207359, 5074.162763144113, 4982.177940639754, 4989.25161225819, 4997.825076122318, 4999.875003124951, 5069.112905241568, 4194.559571244975, 4266.274733295348, 4269.0, 4314.7626880521875, 4330.047389574289, 3681.5582130144553, 3722.739408241397, 3771.0195349208852, 3818.4654148419245, 3838.673712734393, 4312.439382880206, 4391.429114215131, 4391.956080439155, 4395.120975804852, 4444.755538445416, 5099.388073431212, 5118.181091025418, 5120.718360490135, 5122.078025955364, 5181.274621552986, 4297.09760950204, 4340.0, 4345.760983145958, 4382.6055654991205, 4382.715123517025, 4587.587117159472, 4600.7929643166135, 4615.930761038587, 4641.883952901137, 4663.114008338405, 4766.547178018082, 4774.7135171889795, 4781.0, 4785.545373189541, 4837.7097374157665, 4983.651144419856, 4986.828095397651, 5014.52362025607, 5101.51535604117, 5106.412762532208, 4813.542713442217, 4819.3252944588485, 4820.373351464287, 4826.276058591239, 4875.3174555562955, 5035.622328325464, 5035.924461133012, 5040.36995375578, 5040.773165207501, 5044.747762611928, 4954.677945933503, 5004.924926126184, 5006.799728010985, 5077.492250775041, 5169.715665638276, 4904.828331008398, 4946.258061290835, 4969.031038947515, 4979.3526841510375, 5041.369828771403, 4963.255511673278, 4965.4289756678445, 4968.27959945813, 4973.502649734997, 5033.0688822567945, 4780.378550788375, 4902.460729319843, 4902.656814022984, 4916.139675556692, 4959.231319145538, 4888.1690112681645, 4929.852104436873, 4941.937483441077, 4949.777260023379, 5011.574016208663, 4814.783334750015, 4831.613470922248, 4836.588889944394, 4838.903221935471, 4905.460399355997], "value": 4652.9780325122565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.9944000559994, 563.5097465205271, 564.8672561947944, 564.9915251271232, 569.9373068962414, 27793.3051673708, 37284.813575932116, 38480.422793658116, 38490.615093849054, 41938.58061419385, 39141.21717565647, 39162.60837391625, 39343.40984885229, 39609.40585891214, 39621.80189099057, 7602.619869006541, 7777.688892444324, 7784.805379865492, 7786.80532986677, 7799.103103143155, 7310.122785265776, 9850.0, 10112.747181320452, 10114.291999560033, 10401.167906567494, 10122.29143959923, 10198.388096714183, 10215.0, 10221.28451008426, 10230.795384092313, 10026.047525485104, 10103.242256830767, 10104.191664666809, 10110.898891011106, 10114.190864730877, 9906.950465247699, 9955.900440995641, 9998.550065247082, 9999.100080992674, 10009.499525023792, 36337.27325453476, 36583.63416365829, 36625.45061824075, 36626.63373366259, 36648.08379790527, 2318.8260880434036, 2328.557574060923, 2397.5804234258935, 2800.089970759491, 2816.662000559925, 27637.585436218473, 27707.64053445914, 28113.85943070292, 28134.577981330298, 29008.099190081033, 3337.632860385356, 3352.9329413411597, 3385.339858727549, 3402.8638854445794, 3457.3258214648154, 243118.0, 243719.0, 244944.77527612427, 245280.0, 245602.0, 51174.23238651423, 51203.23195152076, 51209.0, 51267.743661281456, 51433.74283128561, 31275.37449251002, 31911.84044079765, 54281.0, 54517.454825452034, 54606.72696636491, 12287.324197169062, 12605.873941260654, 12610.432530536147, 12615.495380184873, 12621.242725436503, 8859.468431894107, 9060.682876099305, 9903.950480247553, 9969.152622027064, 9992.450415227087, 15535.611609709622, 15638.296276667577, 17045.829541704672, 17226.913865430593, 17400.65198696044, 6615.437687796495, 6798.93201067983, 6887.414569761627, 6922.376986071177, 6977.860442791217, 698.8532408194336, 735.988960165598, 773.4006145237373, 836.2933321343455, 921.5023887101024, 27012.324691882466, 27361.452770944867, 27362.83610275683, 27390.452190956068, 28081.719182807912, 887.8224355129, 889.217488610032, 898.4564338575068, 929.6142101027998, 1028.840529717895, 14202.437731849488, 14637.341319640642, 14718.926405367905, 14730.410783568752, 15066.397344106113, 119170.0, 119591.40204298923, 120423.0, 120472.39763801124, 120819.0, 47814.76092619584, 47892.0, 48329.0, 48380.75809620929, 48420.75789621029, 1020.4897551224387, 1063.330102035728, 1080.68660088575, 1083.2363183955135, 1120.618989543559, 24896.875515622305, 28618.713812861606, 28641.856790715912, 28918.27704307325, 29565.704342956295, 40231.0, 40755.18489630132, 40799.0, 40801.387979179744, 40841.79579102086, 274475.0, 276254.0, 276821.0, 277058.0, 277180.0, 23725.64411533794, 24109.879450602635, 24917.377065573506, 24995.62541558549, 26216.475670486863, 38102.61897381102, 38934.22131557296, 39015.21969560536, 39105.804470978605, 39619.0, 29719.702802971693, 30064.24839379033, 30142.0, 30424.0, 30560.541591876583, 30567.0, 30879.0, 31436.214094647818, 31457.528137077505, 32036.0, 33460.83269583636, 33996.49005264972, 34282.14294642654, 34419.4837077439, 34721.826390867885, 28998.565021525115, 31256.531152033192, 31459.0, 31760.841195793873, 31874.84062579672, 24460.51078978446, 24463.633045503975, 24660.876695616407, 24692.506149876543, 24788.752112479367], "value": 16438.102414962803, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.7911894625333, 339.65637103982516, 348.907539502032, 352.7883270037977, 380.5604526771578, 12291.385430728462, 15684.52946411608, 15905.681886362281, 15991.84008159918, 17086.316547338123, 15780.605484862885, 15940.362385504566, 16342.428015019477, 16536.586585335343, 16636.58408539787, 4301.580478442106, 4850.151223535876, 5037.3451451311275, 5059.49405059493, 5082.695038297714, 5032.320636714052, 5095.770690318945, 5100.362454693163, 5105.285260063575, 5109.3613298337705, 4800.895793967389, 4839.830605928793, 4951.851444456672, 5083.313752643383, 5095.0, 4933.679310844784, 4973.005398920194, 4977.328060711794, 4980.103581355354, 4980.97509512452, 4432.645388368923, 4441.955580444186, 4451.732896026248, 4478.798454069574, 4745.193317136095, 11167.497462614201, 11203.0, 11214.943925280402, 11322.830157547643, 11378.71553211168, 1689.565909996015, 1693.9407120750839, 1702.242502086572, 1730.4635562975393, 1752.97370539442, 8082.838343233161, 8419.07390187079, 9291.163795258459, 9679.806403871884, 12874.87125128746, 1638.755264576146, 1649.5216387247683, 1652.818189999099, 1681.9074950877696, 1687.2070127040395, 64882.0, 65691.014634781, 66219.3378066218, 66546.66726666385, 67981.320186798, 9192.678256260999, 9634.566444510014, 9757.46110342976, 9869.753256168653, 9985.452254900501, 13054.282014489105, 13091.672708182181, 13105.803412948813, 13138.474461021453, 13153.079284449997, 6256.343083976208, 6278.0, 6493.383128602775, 6536.444402225864, 6670.699818508178, 5835.562332825055, 5951.464368206882, 6142.78500252489, 6346.841328966812, 6350.523710721714, 4160.916781664349, 4230.49234091911, 4243.0, 4271.444712187458, 4323.805428755713, 1733.54927718794, 1774.5918438759159, 1784.8661350398772, 1801.5225965119255, 1813.9909300453414, 743.3867059675681, 782.1982467970375, 821.4578378270313, 840.2857571064546, 907.609727817045, 15443.922780386027, 15778.368865245491, 16046.679066418606, 16200.189990500663, 16986.150692465573, 763.3320844261272, 765.6478020110811, 801.3315658856112, 820.323233332503, 822.2476434062766, 8599.26906212979, 9033.277337812959, 9069.274458043341, 9317.767055823522, 9378.484183369843, 358202.0, 360081.0, 364001.8597489358, 365050.0, 368404.0, 73668.0, 74092.25907740854, 74353.62823185958, 74472.0, 75168.0, 775.1860546426229, 872.7992561710715, 905.7599736069947, 922.4972390047441, 924.1497822003772, 14405.207713575854, 14964.251787410369, 14971.32629031718, 15940.68118637644, 15998.440054598033, 38323.425148622235, 39058.414123787596, 39559.0, 39646.0, 39848.20303593854, 47013.76493117512, 47325.52674473211, 49676.75161624169, 50906.0, 52180.21729673982, 7060.576365418091, 7140.393066589398, 7228.421726261991, 7287.380572651384, 7301.269873012656, 7716.112647045437, 9739.318247722875, 11472.885271147516, 11499.310041397543, 11646.825297620373, 10029.69910902645, 10106.696799095746, 10111.64609238673, 10275.486225688835, 10671.092957098734, 9865.802683946424, 10000.699979000641, 10035.14701250402, 10444.843327349943, 10448.373097614167, 10068.33872411044, 10119.190464762949, 10238.795224095626, 10484.580616775243, 11060.557577697124, 6784.457243420613, 7065.858682826418, 7135.5361901476235, 7221.855562888818, 7337.156227033746, 9068.637254509746, 9902.306838521234, 9994.250431217542, 10019.54912028975, 10208.438535880598], "value": 7672.455892991748, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.6219172056658, 1284.2037936479385, 1353.9390727417265, 1369.5822774053909, 1673.221951792417, 7049.5417797843165, 7338.633068346581, 7371.189169191392, 7591.2788285112965, 7598.3541398981015, 7382.630868456583, 7480.3267705906455, 7493.625318734055, 7496.250374962501, 7507.061617297838, 2118.745750509937, 2141.175647375756, 2142.689310050042, 2144.04589957469, 2145.442185031895, 2086.311517199328, 2134.039682143042, 2134.7331583552054, 2136.594047131048, 2149.935501934937, 2089.101686274901, 2114.365690292906, 2122.596706625744, 2130.2970019893396, 2136.540643761591, 2097.0458441409123, 2098.2341445372404, 2100.411884672294, 2102.9369118926456, 2103.3269353806786, 2012.5773587546632, 2072.0261477105737, 2073.3572592496225, 2074.1288658763206, 2074.7614024387085, 7324.08448943882, 7436.256374362574, 7492.02603661526, 7542.094948606147, 7549.773506794805, 4362.738235705868, 4550.544945505456, 4558.680892337537, 4563.201439748064, 4581.885452863672, 2760.2961244882426, 2854.971450285512, 2868.18256796814, 2900.5649152627066, 2901.7243361880587, 909.0409617853162, 921.4839689773748, 936.1293996583175, 936.3305236755753, 941.331654525281, 10030.59877604895, 10118.08937195656, 10130.240231982558, 10131.746706332327, 10138.645147419878, 3675.5956844747056, 3855.8072096395067, 3967.484227050465, 4008.0981779100052, 4016.196760647882, 7751.534907905431, 7816.53100813953, 7843.098043724931, 7852.450328477066, 7854.8429031419055, 4352.238358287351, 4353.782310884443, 4356.1287742451, 4359.411479450297, 4361.193179261847, 3503.9474407883904, 3897.376419772823, 4020.8994775130855, 4075.49056367954, 4088.4276201331836, 3364.512145738854, 3380.3577320309064, 3402.8128452935325, 3429.8456569454434, 3485.6165821759587, 1009.6112996496402, 1011.9797604048025, 1023.3041531758475, 1023.6263763726187, 1051.7002654243504, 562.60898675421, 595.3808039638814, 627.1815281058172, 627.3632263252802, 634.9904751428733, 4462.553744625577, 4571.3600095986585, 4602.263637817933, 4669.509701481364, 4670.159371313196, 381.4259539393195, 381.5573934236273, 381.7003652133062, 385.60668118518953, 385.857232823857, 2953.9409211816073, 3101.255698632312, 3128.562001319817, 3128.7340576051292, 3173.841307934594, 8854.690085846965, 9294.628214871329, 9294.953525232331, 9305.53472326381, 9312.115349041831, 9228.630854765868, 9232.215261702697, 9235.399699019543, 9251.953740231256, 9252.398594091363, 6854.246032936373, 6929.44564434844, 6947.89578156328, 6957.0, 6965.33829286217, 4541.772911354353, 4552.11233809404, 4609.585137337655, 4645.279981602857, 4699.6240300776535, 8233.711820086268, 8265.46274492156, 8399.0, 8422.368322375723, 8473.364497662576, 8396.622152003052, 8559.786005349915, 8560.400771945904, 8649.956750216208, 8709.346798990227, 5694.658320500783, 5722.914156287576, 5740.598158128891, 5781.797637082682, 5789.884202316014, 6631.834204144935, 6671.299513551007, 6675.499337549801, 6680.3987641112535, 6699.39705426514, 5703.572232082527, 5775.624584402, 5843.328017277898, 5887.499562537061, 5987.6108052976415, 5656.406077361818, 5698.088305871041, 5769.59612827098, 5987.880242395215, 6009.699515024318, 5379.220013098157, 5562.9443705564045, 5564.276644036249, 5602.1036634138345, 5628.577856660685, 4068.898277543085, 4102.897427564335, 4113.794310284414, 4116.4648595682365, 4206.621404073648, 4222.155568886296, 4265.76538290397, 4267.381229721611, 4376.54046325144, 4436.645068394456], "value": 3744.786483697292, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.18973888595326, 270.2918353912748, 270.6522119076986, 274.88317465077336, 275.10590580613007, 4866.245731911555, 4890.8777280568, 4893.461719210893, 4911.2633105034265, 4948.752562371885, 4833.081714474248, 4843.903121937559, 4888.926666100003, 4901.852944411665, 4909.729964851932, 3530.7351948603828, 3626.854925802978, 3645.4531820227044, 3664.633536646341, 3679.3009328227686, 3620.6379362063844, 3757.530308711411, 3769.962300377002, 3784.735068545203, 3816.3893776995687, 3711.480392745018, 3720.386136287509, 3730.8134593270365, 3742.663160315571, 3766.2467506498674, 3563.768518886279, 3691.280200360931, 3724.29238444696, 3729.160938788778, 3731.8507259709713, 3185.7451403887635, 3199.7440204783793, 3213.586022150246, 3218.5172224166326, 3237.676232376767, 4602.309653551961, 4614.561616646413, 4621.953780462186, 4629.4722741495225, 4655.393889108253, 4185.58144185579, 4217.240896638604, 4219.1139860629, 4220.409142720022, 4225.936610950838, 4818.132736107499, 4981.875453113665, 4983.775730092155, 5017.197248440269, 5058.873528161788, 3513.1217195701074, 3519.771214871025, 3523.8195204606486, 3524.524189234472, 3527.400341941876, 5195.532402083831, 5195.636305458619, 5198.818041368534, 5220.817271395483, 5302.549283310885, 3553.34263161316, 3613.5483064616924, 3650.872219472306, 3652.470391793175, 3658.762180458262, 3665.376885929398, 3681.4661874028116, 3694.6674799268194, 3695.2055308108884, 3695.464157697119, 4236.512801027921, 4243.936340954888, 4247.384129301234, 4254.234237837219, 4270.7864606769535, 4974.1543937530705, 4992.154471950953, 5032.370953630796, 5039.622028347889, 5052.242163675404, 1225.6261840138843, 1273.0642977411594, 1276.4894042382934, 1277.431542963391, 1280.116719463567, 783.824263604593, 793.4009822583912, 803.1526739289989, 803.6303300481844, 808.2442915873677, 3996.800159991989, 4308.116836048603, 4405.757683327448, 4460.30865215892, 4479.148961697332, 5158.767855446514, 5184.222366645034, 5188.898496109082, 5196.090684130262, 5197.506236907488, 4499.280115181556, 4501.662375321864, 4503.707259028152, 4509.774511274423, 4520.593146616755, 2637.2483842104907, 2730.945381092407, 2749.2164733050986, 2757.379589592366, 2772.4039331543813, 4880.585150262196, 4884.902301954012, 4887.902241955141, 4900.828471003569, 4903.338049363361, 5275.868103297448, 5303.390110137384, 5324.201369794562, 5332.760025798849, 5333.386660534011, 4647.744374059459, 4890.853274401774, 4920.286558448934, 4928.137575924199, 4929.482404347564, 5246.842594722164, 5248.947510524846, 5254.605904557097, 5262.553027766819, 5268.602785732559, 4868.683535570177, 5107.41264754558, 5128.564072053917, 5151.793928242828, 5169.948300517097, 4847.0, 4901.2893130496595, 4905.3623029006, 4940.827071052496, 4950.529699678525, 3483.89548313551, 3536.7170626350344, 3543.911402214965, 3556.16430138912, 3579.19468119671, 5130.640855140105, 5255.421903590525, 5274.813167037386, 5285.127953887565, 5299.49654782795, 5021.949780502295, 5057.6459647824295, 5062.139436295912, 5062.215356619729, 5086.21163719624, 5033.5721463676, 5037.5214354636255, 5044.1424957758, 5052.0, 5078.542931136216, 4792.107117688423, 4985.158459678094, 4994.600431965361, 5040.328625828317, 5044.520770526794, 4834.896819883362, 4948.233023881304, 5079.974600126976, 5082.695038297714, 5103.92344114846, 4938.43208031081, 4963.726995015309, 4980.701157930535, 4983.9501604984935, 4997.325361076353], "value": 3766.8244524759148, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.4500211511184, 558.17947617003, 568.442925932586, 576.9826905192845, 587.4008511318453, 11310.09519238461, 12109.878901210985, 12192.329421881794, 12195.51217951282, 12235.571754988567, 11859.881401186007, 12312.876871231285, 12383.876161238384, 12443.875561244386, 12466.750664986695, 729.1396152540001, 752.4018405367746, 756.0247281007494, 757.0650246945019, 758.1773775453639, 609.3540846702488, 738.8780851159579, 757.1482082657011, 757.1822431773671, 770.818857568471, 797.2585495489193, 801.5591424716412, 802.4663598706853, 805.556943680976, 805.8670319397322, 802.0535767794, 803.0363563723555, 803.0443771911407, 803.1526739290047, 803.1687203744096, 473.41296791978044, 567.8608740858475, 580.8431723434692, 582.3245035758542, 589.8731772668854, 11858.644240672707, 11903.761924761457, 11914.285142891455, 11966.64100076999, 11999.040076793835, 11304.491297891615, 12310.384480775925, 12313.13808033438, 12642.999999999907, 12822.743545129139, 10843.67468975932, 10846.945765271123, 10925.180611454172, 11129.332240065622, 11233.382163980978, 3965.8810235692977, 3970.8411663533425, 4058.7564746115327, 4084.060666046796, 4085.7344272622186, 62955.68522157406, 63717.36282637161, 64844.351556484304, 64975.3502464974, 65012.67493662549, 9074.773130671787, 9372.67195648149, 9428.10433008863, 9475.90524094764, 9597.520123993772, 17409.0, 17605.0, 17631.294748210184, 17634.206460709298, 17655.73516397255, 6231.158793562901, 6325.399087086811, 6333.936660633335, 6544.3782840630065, 6631.767888123893, 3578.499010138583, 3626.3472574936764, 3643.6720695137033, 3651.4522821576443, 3692.8552148833946, 2668.2662267876103, 2764.626775385297, 2775.653043369579, 2777.680566734811, 2778.305423644089, 466.1562571745152, 469.2000139761698, 471.86080106368706, 474.80532981477427, 479.9640026997989, 420.268732405612, 429.44387018810716, 430.78891343242213, 447.764170888344, 489.75757000284375, 7126.429885609139, 7435.293647103625, 7934.246246606564, 8055.0, 8110.837783244302, 394.16043826649053, 418.09273875689826, 423.927932251518, 438.9517153113155, 449.9617532509708, 3614.331348700499, 3666.4683620874825, 3734.1971476132217, 3764.6800021997888, 3838.9040273992814, 76694.616526917, 78140.21859781329, 78900.60549697214, 103675.0, 106709.46645266724, 75285.0, 79338.0, 80471.59764201258, 81261.0, 81404.0, 43679.0, 49802.501974980514, 68913.65543172252, 70631.0, 71147.28852711614, 7765.844683106193, 8115.188481151838, 8141.755747327589, 8180.754577362689, 8190.426670133033, 44348.77825610981, 45496.77251613721, 46436.0, 47026.0, 47029.529704702516, 35718.0, 51456.48543514517, 53923.73038134784, 54541.0, 57550.0, 9227.261819054325, 9309.301802364986, 9369.203617692572, 9406.482643454401, 9739.951300243454, 11247.606333778278, 11413.429328533706, 11444.484998174936, 11719.824202637137, 11875.465604048011, 10929.0, 11027.83458248111, 11113.166512511432, 11448.484818183035, 11589.536418543501, 11105.389203593557, 11448.3703396314, 11782.646520604396, 11806.881931180578, 11909.880901190878, 10733.785324293314, 10785.191110666889, 11096.889031109586, 11205.775884482428, 11442.713932151762, 9335.113164249386, 9427.764305892408, 9452.243820494481, 9544.95227523858, 9602.423854568748, 9361.4383137012, 9423.670171543963, 9489.525523723923, 9726.270529710158, 9879.654212102541], "value": 6125.634459414663, "unit": "score"}], "name": "dromaeo_css", "value": 6083.96633494348, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 135.0, 115.0, 115.0, 116.0, 115.0, 115.0, 114.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 129.0, 165.0, 193.0, 125.0, 151.0, 156.0, 126.0, 194.0, 125.0], "value": 152.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 164.0, 162.0, 162.0, 162.0, 163.0, 163.0, 164.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 174.0, 77.0, 75.0, 77.0, 78.0, 159.0, 79.0], "value": 94.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 139.0, 83.0, 83.0, 84.0, 143.0, 83.0, 83.0, 144.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 119.0, 119.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 149.0, 148.0, 147.0, 148.0, 147.0, 148.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 67.0, 64.0, 66.0, 66.0, 66.0, 66.0, 67.0, 65.0], "value": 67.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 82.0, 83.0, 81.0, 88.0, 83.0, 82.0, 83.0, 91.0, 82.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 140.0, 141.0, 134.0, 144.0, 135.0, 143.0, 141.0, 183.0, 134.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 177.0, 173.0, 178.0, 177.0, 178.0, 176.0, 173.0, 175.0, 174.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 68.0, 72.0, 70.0, 69.0, 69.0, 69.0, 68.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1598.8, "alertThreshold": 2.0}]} 18:50:07 INFO - Return code: 0 18:50:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:07 INFO - # TBPL SUCCESS # 18:50:07 INFO - Running post-action listener: _resource_record_post_action 18:50:07 INFO - [mozharness: 2016-08-30 01:50:07.666312Z] Finished run-tests step (success) 18:50:07 INFO - Running post-run listener: _resource_record_post_run 18:50:07 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408072192; Read time: 12; Write time: 297020 18:50:07 INFO - TinderboxPrint: CPU usage
13.0% 18:50:07 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:50:07 INFO - TinderboxPrint: I/O write bytes / time
408,072,192 / 297,020 18:50:07 INFO - TinderboxPrint: CPU idle
8,332.1 (86.9%) 18:50:07 INFO - TinderboxPrint: CPU user
1,201.6 (12.5%) 18:50:07 INFO - TinderboxPrint: Swap in / out
0 / 0 18:50:07 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 124923904; Read time: 0; Write time: 83712 18:50:07 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 283148288; Read time: 0; Write time: 213308 18:50:08 INFO - Running post-run listener: _upload_blobber_files 18:50:08 INFO - Blob upload gear active. 18:50:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:08 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 18:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:50:08 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:12 INFO - (blobuploader) - INFO - Done attempting. 18:50:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:50:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:50:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:13 INFO - (blobuploader) - INFO - Done attempting. 18:50:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:50:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:50:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:14 INFO - (blobuploader) - INFO - Done attempting. 18:50:14 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:14 INFO - Return code: 0 18:50:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a561215eddf5abc1a1349ca252b3ddf8bb122f1786c4e2ea1d161d5056e0eab65f1299a40521911c8e8e175cfac355d344b4a0ecc320f1cfda9e19a3ada887a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7dc302b386b14261959828a041ea1573234efd5949e5999c58d83b7b10b498e0f9fbd422aa29f788a52c9b60c4b3681a472490d66aae537112eb3f57909184dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d950c30b1cc0a390dc6159abf01a4cbafaba171e37f2f992f7e0daec3ea2b270164469cbc6e2305ec9937f7eff92736cfabf296325a2e2226140b70ca0457bc5"} 18:50:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:14 INFO - Contents: 18:50:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a561215eddf5abc1a1349ca252b3ddf8bb122f1786c4e2ea1d161d5056e0eab65f1299a40521911c8e8e175cfac355d344b4a0ecc320f1cfda9e19a3ada887a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7dc302b386b14261959828a041ea1573234efd5949e5999c58d83b7b10b498e0f9fbd422aa29f788a52c9b60c4b3681a472490d66aae537112eb3f57909184dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d950c30b1cc0a390dc6159abf01a4cbafaba171e37f2f992f7e0daec3ea2b270164469cbc6e2305ec9937f7eff92736cfabf296325a2e2226140b70ca0457bc5"} 18:50:14 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:14 INFO - Copying logs to upload dir... 18:50:14 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.898795 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-29 18:50:14.739616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 18:50:14.743086) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a561215eddf5abc1a1349ca252b3ddf8bb122f1786c4e2ea1d161d5056e0eab65f1299a40521911c8e8e175cfac355d344b4a0ecc320f1cfda9e19a3ada887a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7dc302b386b14261959828a041ea1573234efd5949e5999c58d83b7b10b498e0f9fbd422aa29f788a52c9b60c4b3681a472490d66aae537112eb3f57909184dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d950c30b1cc0a390dc6159abf01a4cbafaba171e37f2f992f7e0daec3ea2b270164469cbc6e2305ec9937f7eff92736cfabf296325a2e2226140b70ca0457bc5"} build_url:https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/RMIJpz_ERF-wN_FPa4bkeQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a561215eddf5abc1a1349ca252b3ddf8bb122f1786c4e2ea1d161d5056e0eab65f1299a40521911c8e8e175cfac355d344b4a0ecc320f1cfda9e19a3ada887a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7dc302b386b14261959828a041ea1573234efd5949e5999c58d83b7b10b498e0f9fbd422aa29f788a52c9b60c4b3681a472490d66aae537112eb3f57909184dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d950c30b1cc0a390dc6159abf01a4cbafaba171e37f2f992f7e0daec3ea2b270164469cbc6e2305ec9937f7eff92736cfabf296325a2e2226140b70ca0457bc5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 18:50:14.767900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:50:14.768231) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1472515071.605233-1774021985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:50:14.852916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 18:50:14.853286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 18:50:14.853654) ========= ========= Total master_lag: 0.74 =========