builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1472222196.8 results: success (0) buildid: 20160826062517 builduid: 950795c823814dcaae95dce96cf7b3d8 revision: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.796842) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.797297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.797604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.844473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.844779) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-26 07:36:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.8M=0s 2016-08-26 07:36:36 (38.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120379 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.982492) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:36.982807) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014591 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.031950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.032356) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 --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 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 --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=20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-26 07:36:37,079 truncating revision to first 12 chars 2016-08-26 07:36:37,079 Setting DEBUG logging. 2016-08-26 07:36:37,079 attempt 1/10 2016-08-26 07:36:37,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/717ef7ea17e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 07:36:37,384 unpacking tar archive at: mozilla-central-717ef7ea17e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.692139) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.692545) ========= script_repo_revision: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.693086) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.693396) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 07:36:37.703974) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-26 07:36:37.704383) ========= /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=20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:36:37 INFO - MultiFileLogger online at 20160826 07:36:37 in /builds/slave/test 07:36:37 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 07:36:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:36:37 INFO - {'append_to_log': False, 07:36:37 INFO - 'base_work_dir': '/builds/slave/test', 07:36:37 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:36:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:36:37 INFO - 'branch': 'Firefox-Non-PGO', 07:36:37 INFO - 'buildbot_json_path': 'buildprops.json', 07:36:37 INFO - 'config_files': ('talos/linux_config.py',), 07:36:37 INFO - 'default_actions': ('clobber', 07:36:37 INFO - 'read-buildbot-config', 07:36:37 INFO - 'download-and-extract', 07:36:37 INFO - 'populate-webroot', 07:36:37 INFO - 'create-virtualenv', 07:36:37 INFO - 'install', 07:36:37 INFO - 'run-tests'), 07:36:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:36:37 INFO - 'download_minidump_stackwalk': True, 07:36:37 INFO - 'download_symbols': 'ondemand', 07:36:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:36:37 INFO - 'tooltool.py': '/tools/tooltool.py', 07:36:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:36:37 INFO - '/tools/misc-python/virtualenv.py')}, 07:36:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:36:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:36:37 INFO - 'installer_path': 'installer.exe', 07:36:37 INFO - 'log_level': 'info', 07:36:37 INFO - 'log_name': 'talos', 07:36:37 INFO - 'log_to_console': True, 07:36:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:36:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:36:37 INFO - 'opt_config_files': (), 07:36:37 INFO - 'pip_index': False, 07:36:37 INFO - 'sps_profile': False, 07:36:37 INFO - 'sps_profile_interval': 0, 07:36:37 INFO - 'suite': 'dromaeojs', 07:36:37 INFO - 'system_bits': '32', 07:36:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:36:37 INFO - 'title': 'talos-linux64-ix-015', 07:36:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:36:37 INFO - 'use_talos_json': True, 07:36:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:36:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:36:37 INFO - 'work_dir': 'build'} 07:36:37 INFO - [mozharness: 2016-08-26 14:36:37.849532Z] Running clobber step. 07:36:37 INFO - Running pre-action listener: _resource_record_pre_action 07:36:37 INFO - Running main action method: clobber 07:36:37 INFO - rmtree: /builds/slave/test/build 07:36:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:36:38 INFO - Running post-action listener: _resource_record_post_action 07:36:38 INFO - [mozharness: 2016-08-26 14:36:38.294191Z] Finished clobber step (success) 07:36:38 INFO - [mozharness: 2016-08-26 14:36:38.294293Z] Running read-buildbot-config step. 07:36:38 INFO - Running pre-action listener: _resource_record_pre_action 07:36:38 INFO - Running main action method: read_buildbot_config 07:36:38 INFO - Using buildbot properties: 07:36:38 INFO - { 07:36:38 INFO - "project": "", 07:36:38 INFO - "product": "firefox", 07:36:38 INFO - "script_repo_revision": "production", 07:36:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:36:38 INFO - "repository": "", 07:36:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:36:38 INFO - "buildid": "20160826062517", 07:36:38 INFO - "pgo_build": "False", 07:36:38 INFO - "basedir": "/builds/slave/test", 07:36:38 INFO - "buildnumber": 188, 07:36:38 INFO - "slavename": "talos-linux64-ix-015", 07:36:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:36:38 INFO - "platform": "ubuntu64_hw", 07:36:38 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 07:36:38 INFO - "branch": "mozilla-central", 07:36:38 INFO - "repo_path": "mozilla-central", 07:36:38 INFO - "revision": "717ef7ea17e82c6fe24fed6fe89a532dcdb28a19", 07:36:38 INFO - "stage_platform": "linux64", 07:36:38 INFO - "builduid": "950795c823814dcaae95dce96cf7b3d8", 07:36:38 INFO - "slavebuilddir": "test" 07:36:38 INFO - } 07:36:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:36:38 INFO - Running post-action listener: _resource_record_post_action 07:36:38 INFO - [mozharness: 2016-08-26 14:36:38.296723Z] Finished read-buildbot-config step (success) 07:36:38 INFO - [mozharness: 2016-08-26 14:36:38.296814Z] Running download-and-extract step. 07:36:38 INFO - Running pre-action listener: _resource_record_pre_action 07:36:38 INFO - Running main action method: download_and_extract 07:36:38 INFO - mkdir: /builds/slave/test/build/tests 07:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:38 INFO - trying https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:36:38 INFO - Downloading https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:36:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:36:38 INFO - Downloaded 1361 bytes. 07:36:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:36:38 INFO - Using the following test package requirements: 07:36:38 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:36:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:36:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:36:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'jsshell-linux-x86_64.zip'], 07:36:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:36:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:36:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:36:38 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:36:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:36:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:36:38 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:36:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:36:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:38 INFO - trying https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:36:38 INFO - Downloading https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:36:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:36:40 INFO - Downloaded 20302574 bytes. 07:36:40 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 07:36:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:44 INFO - trying https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:36:44 INFO - Downloading https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:36:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:36:45 INFO - Downloaded 20302574 bytes. 07:36:45 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 07:36: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')]} 07:36:49 INFO - trying https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:36:49 INFO - Downloading https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:36:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:36:51 INFO - Downloaded 14413998 bytes. 07:36:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 07:36: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')]} 07:36:51 INFO - trying https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:36:51 INFO - Downloading https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:36:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:36:53 INFO - Downloaded 55909195 bytes. 07:36:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:36:53 INFO - mkdir: /builds/slave/test/properties 07:36:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:36:53 INFO - Writing to file /builds/slave/test/properties/build_url 07:36:53 INFO - Contents: 07:36:53 INFO - build_url:https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:36:54 INFO - Running post-action listener: _resource_record_post_action 07:36:54 INFO - Running post-action listener: set_extra_try_arguments 07:36:54 INFO - [mozharness: 2016-08-26 14:36:54.609721Z] Finished download-and-extract step (success) 07:36:54 INFO - [mozharness: 2016-08-26 14:36:54.609948Z] Running populate-webroot step. 07:36:54 INFO - Running pre-action listener: _resource_record_pre_action 07:36:54 INFO - Running main action method: populate_webroot 07:36:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:36:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:36:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:36:54 INFO - '--tppagecycles', 07:36:54 INFO - '7'], 07:36:54 INFO - 'tests': ['tsvgm']}}, 07:36:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:36:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:36:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:36:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:36:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['damp', 'tps']}, 07:36:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'tests': ['damp', 'tps']}, 07:36:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['dromaeo_dom']}, 07:36:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:36:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['basic_compositor_video']}, 07:36:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:36:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['a11yr', 07:36:54 INFO - 'ts_paint', 07:36:54 INFO - 'tpaint', 07:36:54 INFO - 'sessionrestore', 07:36:54 INFO - 'sessionrestore_no_auto_restore', 07:36:54 INFO - 'tabpaint']}, 07:36:54 INFO - 'other-e10s': {'tests': ['a11yr', 07:36:54 INFO - 'ts_paint', 07:36:54 INFO - 'tpaint', 07:36:54 INFO - 'sessionrestore', 07:36:54 INFO - 'sessionrestore_no_auto_restore', 07:36:54 INFO - 'tabpaint']}, 07:36:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:36:54 INFO - 'ts_paint', 07:36:54 INFO - 'tpaint', 07:36:54 INFO - 'sessionrestore', 07:36:54 INFO - 'sessionrestore_no_auto_restore', 07:36:54 INFO - 'tabpaint']}, 07:36:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:36:54 INFO - 'ts_paint', 07:36:54 INFO - 'tpaint', 07:36:54 INFO - 'sessionrestore', 07:36:54 INFO - 'sessionrestore_no_auto_restore', 07:36:54 INFO - 'tabpaint']}, 07:36:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['a11yr', 07:36:54 INFO - 'ts_paint', 07:36:54 INFO - 'tpaint', 07:36:54 INFO - 'sessionrestore', 07:36:54 INFO - 'sessionrestore_no_auto_restore', 07:36:54 INFO - 'tabpaint']}, 07:36:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['a11yr', 07:36:54 INFO - 'ts_paint', 07:36:54 INFO - 'tpaint', 07:36:54 INFO - 'sessionrestore', 07:36:54 INFO - 'sessionrestore_no_auto_restore', 07:36:54 INFO - 'tabpaint']}, 07:36:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['tsvgx', 07:36:54 INFO - 'tsvgr_opacity', 07:36:54 INFO - 'tart', 07:36:54 INFO - 'tscrollx', 07:36:54 INFO - 'cart']}, 07:36:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:36:54 INFO - 'tsvgr_opacity', 07:36:54 INFO - 'tart', 07:36:54 INFO - 'tscrollx', 07:36:54 INFO - 'cart']}, 07:36:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'talos_options': ['--disable-e10s'], 07:36:54 INFO - 'tests': ['tp5o']}, 07:36:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'tests': ['tp5o']}, 07:36:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'talos_options': ['--disable-e10s', 07:36:54 INFO - '--xperf_path', 07:36:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:54 INFO - 'tests': ['tp5n']}, 07:36:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:54 INFO - 'talos_options': ['--xperf_path', 07:36:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:54 INFO - 'tests': ['tp5n']}}, 07:36:54 INFO - 'talos.zip': {'path': '', 07:36:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:36:54 INFO - Running post-action listener: _resource_record_post_action 07:36:54 INFO - [mozharness: 2016-08-26 14:36:54.625258Z] Finished populate-webroot step (success) 07:36:54 INFO - [mozharness: 2016-08-26 14:36:54.625347Z] Running create-virtualenv step. 07:36:54 INFO - Running pre-action listener: _resource_record_pre_action 07:36:54 INFO - Running main action method: create_virtualenv 07:36:54 INFO - Creating virtualenv /builds/slave/test/build/venv 07:36:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:36:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:36:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:36:54 INFO - Using real prefix '/usr' 07:36:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:36:55 INFO - Installing distribute.............................................................................................................................................................................................done. 07:36:57 INFO - Installing pip.................done. 07:36:57 INFO - Return code: 0 07:36:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:57 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:36:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:36:57 INFO - Using env: {'DISPLAY': ':0', 07:36:57 INFO - 'HOME': '/home/cltbld', 07:36:57 INFO - 'LANG': 'en_US.UTF-8', 07:36:57 INFO - 'LANGUAGE': 'en_US:en', 07:36:57 INFO - 'LOGNAME': 'cltbld', 07:36:57 INFO - 'MAIL': '/var/mail/cltbld', 07:36:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:57 INFO - 'MOZ_NO_REMOTE': '1', 07:36:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:57 INFO - 'NO_EM_RESTART': '1', 07:36:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:57 INFO - 'PWD': '/builds/slave/test', 07:36:57 INFO - 'SHELL': '/bin/bash', 07:36:57 INFO - 'SHLVL': '1', 07:36:57 INFO - 'TERM': 'linux', 07:36:57 INFO - 'TMOUT': '86400', 07:36:57 INFO - 'USER': 'cltbld', 07:36:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:36:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:57 INFO - '_': '/tools/buildbot/bin/python'} 07:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:36:57 INFO - Cleaning up... 07:36:57 INFO - Return code: 0 07:36:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:57 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:36:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:36:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:36:57 INFO - Using env: {'DISPLAY': ':0', 07:36:57 INFO - 'HOME': '/home/cltbld', 07:36:57 INFO - 'LANG': 'en_US.UTF-8', 07:36:57 INFO - 'LANGUAGE': 'en_US:en', 07:36:57 INFO - 'LOGNAME': 'cltbld', 07:36:57 INFO - 'MAIL': '/var/mail/cltbld', 07:36:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:57 INFO - 'MOZ_NO_REMOTE': '1', 07:36:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:57 INFO - 'NO_EM_RESTART': '1', 07:36:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:57 INFO - 'PWD': '/builds/slave/test', 07:36:57 INFO - 'SHELL': '/bin/bash', 07:36:57 INFO - 'SHLVL': '1', 07:36:57 INFO - 'TERM': 'linux', 07:36:57 INFO - 'TMOUT': '86400', 07:36:57 INFO - 'USER': 'cltbld', 07:36:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:36:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:57 INFO - '_': '/tools/buildbot/bin/python'} 07:36:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:57 INFO - Downloading/unpacking psutil>=3.1.1 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36: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 07:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:36:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:59 INFO - Installing collected packages: psutil 07:36:59 INFO - Running setup.py install for psutil 07:36:59 INFO - building 'psutil._psutil_linux' extension 07:36:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:36:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:36:59 INFO - building 'psutil._psutil_posix' extension 07:36:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:36:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:37:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:37:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:37:00 INFO - Successfully installed psutil 07:37:00 INFO - Cleaning up... 07:37:00 INFO - Return code: 0 07:37:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:00 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:37:00 INFO - Using env: {'DISPLAY': ':0', 07:37:00 INFO - 'HOME': '/home/cltbld', 07:37:00 INFO - 'LANG': 'en_US.UTF-8', 07:37:00 INFO - 'LANGUAGE': 'en_US:en', 07:37:00 INFO - 'LOGNAME': 'cltbld', 07:37:00 INFO - 'MAIL': '/var/mail/cltbld', 07:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:00 INFO - 'MOZ_NO_REMOTE': '1', 07:37:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:00 INFO - 'NO_EM_RESTART': '1', 07:37:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:00 INFO - 'PWD': '/builds/slave/test', 07:37:00 INFO - 'SHELL': '/bin/bash', 07:37:00 INFO - 'SHLVL': '1', 07:37:00 INFO - 'TERM': 'linux', 07:37:00 INFO - 'TMOUT': '86400', 07:37:00 INFO - 'USER': 'cltbld', 07:37:00 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:00 INFO - '_': '/tools/buildbot/bin/python'} 07:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:37:02 INFO - Installing collected packages: mozsystemmonitor 07:37:02 INFO - Running setup.py install for mozsystemmonitor 07:37:02 INFO - Successfully installed mozsystemmonitor 07:37:02 INFO - Cleaning up... 07:37:02 INFO - Return code: 0 07:37:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:02 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:37:02 INFO - Using env: {'DISPLAY': ':0', 07:37:02 INFO - 'HOME': '/home/cltbld', 07:37:02 INFO - 'LANG': 'en_US.UTF-8', 07:37:02 INFO - 'LANGUAGE': 'en_US:en', 07:37:02 INFO - 'LOGNAME': 'cltbld', 07:37:02 INFO - 'MAIL': '/var/mail/cltbld', 07:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:02 INFO - 'MOZ_NO_REMOTE': '1', 07:37:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:02 INFO - 'NO_EM_RESTART': '1', 07:37:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:02 INFO - 'PWD': '/builds/slave/test', 07:37:02 INFO - 'SHELL': '/bin/bash', 07:37:02 INFO - 'SHLVL': '1', 07:37:02 INFO - 'TERM': 'linux', 07:37:02 INFO - 'TMOUT': '86400', 07:37:02 INFO - 'USER': 'cltbld', 07:37:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:02 INFO - '_': '/tools/buildbot/bin/python'} 07:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:02 INFO - Downloading/unpacking jsonschema==2.5.1 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37: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 07:37:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:37:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:04 INFO - Downloading functools32-3.2.3-2.tar.gz 07:37:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:37:04 INFO - warning: no files found matching '*.txt' 07:37:04 INFO - no previously-included directories found matching 'build' 07:37:04 INFO - no previously-included directories found matching 'dist' 07:37:04 INFO - no previously-included directories found matching '.git*' 07:37:04 INFO - Installing collected packages: jsonschema, functools32 07:37:04 INFO - Running setup.py install for functools32 07:37:04 INFO - warning: no files found matching '*.txt' 07:37:04 INFO - no previously-included directories found matching 'build' 07:37:04 INFO - no previously-included directories found matching 'dist' 07:37:04 INFO - no previously-included directories found matching '.git*' 07:37:04 INFO - Successfully installed jsonschema functools32 07:37:04 INFO - Cleaning up... 07:37:05 INFO - Return code: 0 07:37:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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', '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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:37: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 functools32==3.2.3-2 07:37:05 INFO - Using env: {'DISPLAY': ':0', 07:37:05 INFO - 'HOME': '/home/cltbld', 07:37:05 INFO - 'LANG': 'en_US.UTF-8', 07:37:05 INFO - 'LANGUAGE': 'en_US:en', 07:37:05 INFO - 'LOGNAME': 'cltbld', 07:37:05 INFO - 'MAIL': '/var/mail/cltbld', 07:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:05 INFO - 'MOZ_NO_REMOTE': '1', 07:37:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:05 INFO - 'NO_EM_RESTART': '1', 07:37:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:05 INFO - 'PWD': '/builds/slave/test', 07:37:05 INFO - 'SHELL': '/bin/bash', 07:37:05 INFO - 'SHLVL': '1', 07:37:05 INFO - 'TERM': 'linux', 07:37:05 INFO - 'TMOUT': '86400', 07:37:05 INFO - 'USER': 'cltbld', 07:37:05 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:05 INFO - '_': '/tools/buildbot/bin/python'} 07:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:37:05 INFO - Cleaning up... 07:37:05 INFO - Return code: 0 07:37:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37: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', '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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:37: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 blobuploader==1.2.4 07:37:05 INFO - Using env: {'DISPLAY': ':0', 07:37:05 INFO - 'HOME': '/home/cltbld', 07:37:05 INFO - 'LANG': 'en_US.UTF-8', 07:37:05 INFO - 'LANGUAGE': 'en_US:en', 07:37:05 INFO - 'LOGNAME': 'cltbld', 07:37:05 INFO - 'MAIL': '/var/mail/cltbld', 07:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:05 INFO - 'MOZ_NO_REMOTE': '1', 07:37:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:05 INFO - 'NO_EM_RESTART': '1', 07:37:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:05 INFO - 'PWD': '/builds/slave/test', 07:37:05 INFO - 'SHELL': '/bin/bash', 07:37:05 INFO - 'SHLVL': '1', 07:37:05 INFO - 'TERM': 'linux', 07:37:05 INFO - 'TMOUT': '86400', 07:37:05 INFO - 'USER': 'cltbld', 07:37:05 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:05 INFO - '_': '/tools/buildbot/bin/python'} 07:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:05 INFO - Downloading/unpacking blobuploader==1.2.4 07:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:07 INFO - Downloading blobuploader-1.2.4.tar.gz 07:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:37:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:37:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:08 INFO - Downloading docopt-0.6.1.tar.gz 07:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:37:08 INFO - Installing collected packages: blobuploader, requests, docopt 07:37:08 INFO - Running setup.py install for blobuploader 07:37:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:37:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:37:08 INFO - Running setup.py install for requests 07:37:08 INFO - Running setup.py install for docopt 07:37:08 INFO - Successfully installed blobuploader requests docopt 07:37:08 INFO - Cleaning up... 07:37:08 INFO - Return code: 0 07:37:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:08 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:37:08 INFO - Using env: {'DISPLAY': ':0', 07:37:08 INFO - 'HOME': '/home/cltbld', 07:37:08 INFO - 'LANG': 'en_US.UTF-8', 07:37:08 INFO - 'LANGUAGE': 'en_US:en', 07:37:08 INFO - 'LOGNAME': 'cltbld', 07:37:08 INFO - 'MAIL': '/var/mail/cltbld', 07:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:08 INFO - 'MOZ_NO_REMOTE': '1', 07:37:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:08 INFO - 'NO_EM_RESTART': '1', 07:37:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:08 INFO - 'PWD': '/builds/slave/test', 07:37:08 INFO - 'SHELL': '/bin/bash', 07:37:08 INFO - 'SHLVL': '1', 07:37:08 INFO - 'TERM': 'linux', 07:37:08 INFO - 'TMOUT': '86400', 07:37:08 INFO - 'USER': 'cltbld', 07:37:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:08 INFO - '_': '/tools/buildbot/bin/python'} 07:37:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:08 INFO - Running setup.py (path:/tmp/pip-bEIodj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:09 INFO - Running setup.py (path:/tmp/pip-2AgqQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:09 INFO - Running setup.py (path:/tmp/pip-b4I7KZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:09 INFO - Running setup.py (path:/tmp/pip-L3P9Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:09 INFO - Running setup.py (path:/tmp/pip-Ifl4Xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:09 INFO - Running setup.py (path:/tmp/pip-RQ3_Oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:09 INFO - Running setup.py (path:/tmp/pip-eUhKOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:09 INFO - Running setup.py (path:/tmp/pip-NHjvAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:09 INFO - Running setup.py (path:/tmp/pip-Gx3cIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:09 INFO - Running setup.py (path:/tmp/pip-TxZazH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:09 INFO - Running setup.py (path:/tmp/pip-9gGlP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:09 INFO - Running setup.py (path:/tmp/pip-vlWn_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:09 INFO - Running setup.py (path:/tmp/pip-36fG7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:09 INFO - Running setup.py (path:/tmp/pip-Q_Ye7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:09 INFO - Running setup.py (path:/tmp/pip-bxMCfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:10 INFO - Running setup.py (path:/tmp/pip-b5ZJXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:10 INFO - Running setup.py (path:/tmp/pip-i__mKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:37:10 INFO - Running setup.py install for manifestparser 07:37:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Running setup.py install for mozcrash 07:37:10 INFO - Running setup.py install for mozdebug 07:37:10 INFO - Running setup.py install for mozdevice 07:37:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Running setup.py install for mozfile 07:37:10 INFO - Running setup.py install for mozhttpd 07:37:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Running setup.py install for mozinfo 07:37:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Running setup.py install for mozInstall 07:37:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:37:10 INFO - Running setup.py install for mozleak 07:37:11 INFO - Running setup.py install for mozlog 07:37:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Running setup.py install for moznetwork 07:37:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Running setup.py install for mozprocess 07:37:11 INFO - Running setup.py install for mozprofile 07:37:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Running setup.py install for mozrunner 07:37:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Running setup.py install for mozscreenshot 07:37:11 INFO - Running setup.py install for moztest 07:37:11 INFO - Running setup.py install for mozversion 07:37:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:37:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:37:11 INFO - Cleaning up... 07:37:11 INFO - Return code: 0 07:37:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:11 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:37:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:37:11 INFO - Using env: {'DISPLAY': ':0', 07:37:11 INFO - 'HOME': '/home/cltbld', 07:37:11 INFO - 'LANG': 'en_US.UTF-8', 07:37:11 INFO - 'LANGUAGE': 'en_US:en', 07:37:11 INFO - 'LOGNAME': 'cltbld', 07:37:11 INFO - 'MAIL': '/var/mail/cltbld', 07:37:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:11 INFO - 'MOZ_NO_REMOTE': '1', 07:37:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:11 INFO - 'NO_EM_RESTART': '1', 07:37:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:11 INFO - 'PWD': '/builds/slave/test', 07:37:11 INFO - 'SHELL': '/bin/bash', 07:37:11 INFO - 'SHLVL': '1', 07:37:11 INFO - 'TERM': 'linux', 07:37:11 INFO - 'TMOUT': '86400', 07:37:11 INFO - 'USER': 'cltbld', 07:37:11 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:11 INFO - '_': '/tools/buildbot/bin/python'} 07:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:37:12 INFO - Running setup.py (path:/tmp/pip-m2jc9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:37:12 INFO - Running setup.py (path:/tmp/pip-IL12o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:37:12 INFO - Running setup.py (path:/tmp/pip-D52si_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:37:12 INFO - Running setup.py (path:/tmp/pip-icvwuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:37:12 INFO - Running setup.py (path:/tmp/pip-KQeu1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:37:12 INFO - Running setup.py (path:/tmp/pip-NaUwL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:37:12 INFO - Running setup.py (path:/tmp/pip-O4Ar2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:37:12 INFO - Running setup.py (path:/tmp/pip-esEuhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:37:12 INFO - Running setup.py (path:/tmp/pip-UNxLZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:37:12 INFO - Running setup.py (path:/tmp/pip-WJLkdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:37:12 INFO - Running setup.py (path:/tmp/pip-Ad8Tvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:37:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:37:12 INFO - Running setup.py (path:/tmp/pip-VO6wIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:37:13 INFO - Running setup.py (path:/tmp/pip-vgvFOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:37:13 INFO - Running setup.py (path:/tmp/pip-Ggnzj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:13 INFO - Running setup.py (path:/tmp/pip-mNW4ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:37:13 INFO - Running setup.py (path:/tmp/pip-01DjfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:37:13 INFO - Running setup.py (path:/tmp/pip-IzfEh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:37:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:37:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:37:15 INFO - Downloading blessings-1.6.tar.gz 07:37:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:37:15 INFO - Installing collected packages: blessings 07:37:15 INFO - Running setup.py install for blessings 07:37:15 INFO - Successfully installed blessings 07:37:15 INFO - Cleaning up... 07:37:15 INFO - Return code: 0 07:37:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:37:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:15 INFO - Reading from file tmpfile_stdout 07:37:15 INFO - Current package versions: 07:37:15 INFO - argparse == 1.2.1 07:37:15 INFO - blessings == 1.6 07:37:15 INFO - blobuploader == 1.2.4 07:37:15 INFO - docopt == 0.6.1 07:37:15 INFO - functools32 == 3.2.3-2 07:37:15 INFO - jsonschema == 2.5.1 07:37:15 INFO - manifestparser == 1.1 07:37:15 INFO - mozInstall == 1.12 07:37:15 INFO - mozcrash == 0.17 07:37:15 INFO - mozdebug == 0.1 07:37:15 INFO - mozdevice == 0.48 07:37:15 INFO - mozfile == 1.2 07:37:15 INFO - mozhttpd == 0.7 07:37:15 INFO - mozinfo == 0.9 07:37:15 INFO - mozleak == 0.1 07:37:15 INFO - mozlog == 3.3 07:37:15 INFO - moznetwork == 0.27 07:37:15 INFO - mozprocess == 0.23 07:37:15 INFO - mozprofile == 0.28 07:37:15 INFO - mozrunner == 6.12 07:37:15 INFO - mozscreenshot == 0.1 07:37:15 INFO - mozsystemmonitor == 0.3 07:37:15 INFO - moztest == 0.7 07:37:15 INFO - mozversion == 1.4 07:37:15 INFO - psutil == 3.1.1 07:37:15 INFO - requests == 1.2.3 07:37:15 INFO - wsgiref == 0.1.2 07:37:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:15 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 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:37:15 INFO - Using env: {'DISPLAY': ':0', 07:37:15 INFO - 'HOME': '/home/cltbld', 07:37:15 INFO - 'LANG': 'en_US.UTF-8', 07:37:15 INFO - 'LANGUAGE': 'en_US:en', 07:37:15 INFO - 'LOGNAME': 'cltbld', 07:37:15 INFO - 'MAIL': '/var/mail/cltbld', 07:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:15 INFO - 'MOZ_NO_REMOTE': '1', 07:37:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:15 INFO - 'NO_EM_RESTART': '1', 07:37:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:15 INFO - 'PWD': '/builds/slave/test', 07:37:15 INFO - 'SHELL': '/bin/bash', 07:37:15 INFO - 'SHLVL': '1', 07:37:15 INFO - 'TERM': 'linux', 07:37:15 INFO - 'TMOUT': '86400', 07:37:15 INFO - 'USER': 'cltbld', 07:37:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:15 INFO - '_': '/tools/buildbot/bin/python'} 07:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:37:15 INFO - Cleaning up... 07:37:15 INFO - Return code: 0 07:37:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:37:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:37:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:37:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c60653f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b5a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, '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': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:37:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:37:15 INFO - Using env: {'DISPLAY': ':0', 07:37:15 INFO - 'HOME': '/home/cltbld', 07:37:15 INFO - 'LANG': 'en_US.UTF-8', 07:37:15 INFO - 'LANGUAGE': 'en_US:en', 07:37:15 INFO - 'LOGNAME': 'cltbld', 07:37:15 INFO - 'MAIL': '/var/mail/cltbld', 07:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:15 INFO - 'MOZ_NO_REMOTE': '1', 07:37:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:15 INFO - 'NO_EM_RESTART': '1', 07:37:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:15 INFO - 'PWD': '/builds/slave/test', 07:37:15 INFO - 'SHELL': '/bin/bash', 07:37:15 INFO - 'SHLVL': '1', 07:37:15 INFO - 'TERM': 'linux', 07:37:15 INFO - 'TMOUT': '86400', 07:37:15 INFO - 'USER': 'cltbld', 07:37:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:15 INFO - '_': '/tools/buildbot/bin/python'} 07:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:37:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:37:16 INFO - Cleaning up... 07:37:16 INFO - Return code: 0 07:37:16 INFO - Running post-action listener: _resource_record_post_action 07:37:16 INFO - Running post-action listener: _start_resource_monitoring 07:37:16 INFO - Starting resource monitoring. 07:37:16 INFO - [mozharness: 2016-08-26 14:37:16.239334Z] Finished create-virtualenv step (success) 07:37:16 INFO - [mozharness: 2016-08-26 14:37:16.239794Z] Running install step. 07:37:16 INFO - Running pre-action listener: _resource_record_pre_action 07:37:16 INFO - Running main action method: install 07:37:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:37:16 INFO - Reading from file tmpfile_stdout 07:37:16 INFO - Detecting whether we're running mozinstall >=1.0... 07:37:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:37:16 INFO - Reading from file tmpfile_stdout 07:37:16 INFO - Output received: 07:37:16 INFO - Usage: mozinstall [options] installer 07:37:16 INFO - Options: 07:37:16 INFO - -h, --help show this help message and exit 07:37:16 INFO - -d DEST, --destination=DEST 07:37:16 INFO - Directory to install application into. [default: 07:37:16 INFO - "/builds/slave/test"] 07:37:16 INFO - --app=APP Application being installed. [default: firefox] 07:37:16 INFO - mkdir: /builds/slave/test/build/application 07:37:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:37:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:37:33 INFO - Reading from file tmpfile_stdout 07:37:33 INFO - Output received: 07:37:33 INFO - /builds/slave/test/build/application/firefox/firefox 07:37:33 INFO - Running post-action listener: _resource_record_post_action 07:37:33 INFO - [mozharness: 2016-08-26 14:37:33.224514Z] Finished install step (success) 07:37:33 INFO - [mozharness: 2016-08-26 14:37:33.224800Z] Running run-tests step. 07:37:33 INFO - Running pre-action listener: _resource_record_pre_action 07:37:33 INFO - Running main action method: run_tests 07:37:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:37:33 INFO - Python 2.7.3 07:37:33 INFO - Return code: 0 07:37:33 INFO - grabbing minidump binary from tooltool 07:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:33 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 0x1b54090>, '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 0x1b579b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b57e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:37:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:37:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:37:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:37:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:37:33 INFO - Return code: 0 07:37:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:37:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:37:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:37:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:37:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:37:33 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/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:37:33 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/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:37:33 INFO - Using env: {'DISPLAY': ':0', 07:37:33 INFO - 'HOME': '/home/cltbld', 07:37:33 INFO - 'LANG': 'en_US.UTF-8', 07:37:33 INFO - 'LANGUAGE': 'en_US:en', 07:37:33 INFO - 'LOGNAME': 'cltbld', 07:37:33 INFO - 'MAIL': '/var/mail/cltbld', 07:37:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:37:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:33 INFO - 'MOZ_NO_REMOTE': '1', 07:37:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:33 INFO - 'NO_EM_RESTART': '1', 07:37:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:33 INFO - 'PWD': '/builds/slave/test', 07:37:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:37:33 INFO - 'SHELL': '/bin/bash', 07:37:33 INFO - 'SHLVL': '1', 07:37:33 INFO - 'TERM': 'linux', 07:37:33 INFO - 'TMOUT': '86400', 07:37:33 INFO - 'USER': 'cltbld', 07:37:33 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962', 07:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:33 INFO - '_': '/tools/buildbot/bin/python'} 07:37:33 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/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:37:33 INFO - mozversion application_buildid: 20160826062517 07:37:33 INFO - mozversion application_changeset: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 07:37:33 INFO - mozversion application_display_name: Nightly 07:37:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:37:33 INFO - mozversion application_name: Firefox 07:37:33 INFO - mozversion application_remotingname: firefox 07:37:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:37:33 INFO - mozversion application_vendor: Mozilla 07:37:33 INFO - mozversion application_version: 51.0a1 07:37:33 INFO - mozversion platform_buildid: 20160826062517 07:37:33 INFO - mozversion platform_changeset: 717ef7ea17e82c6fe24fed6fe89a532dcdb28a19 07:37:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:37:33 INFO - mozversion platform_version: 51.0a1 07:37:33 INFO - using testdate: 1472222253 07:37:33 INFO - actual date: 1472222253 07:37:33 INFO - starting webserver on 'localhost:50022' 07:37:33 INFO - SUITE-START | Running 2 tests 07:37:33 INFO - TEST-START | dromaeo_css 07:37:33 INFO - operating with platform_type : linux_ 07:37:33 INFO - Initialising browser for dromaeo_css test... 07:37:33 INFO - TEST-INFO | started process 4701 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuSjoEk/profile http://localhost:50022/getInfo.html) 07:37:37 INFO - PROCESS | 4701 | __metrics Screen width/height:1600/1200 07:37:37 INFO - PROCESS | 4701 | colorDepth:24 07:37:37 INFO - PROCESS | 4701 | Browser inner width/height: 1024/697 07:37:37 INFO - PROCESS | 4701 | __metrics 07:37:38 INFO - PROCESS | 4701 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:37:38 INFO - PROCESS | 4701 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:37:38 INFO - PROCESS | 4701 | console.error: PushService: 07:37:38 INFO - PROCESS | 4701 | stateChangeProcessEnqueue: Error transitioning state 07:37:38 INFO - PROCESS | 4701 | UnknownError 07:37:39 INFO - TEST-INFO | 4701: exit 0 07:37:39 INFO - Browser initialized. 07:37:39 INFO - Running cycle 1/1 for dromaeo_css test... 07:37:39 INFO - TEST-INFO | started process 4780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuSjoEk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:37:51 INFO - PROCESS | 4780 | RSS: Main: 180989952 07:37:51 INFO - PROCESS | 4780 | 07:38:42 INFO - PROCESS | 4780 | 1472222322674 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:40:55 INFO - PROCESS | 4780 | Cycle 1(1): loaded http://localhost:50022/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50022/tests/dromaeo/cssquery-ext.html) 07:40:57 INFO - PROCESS | 4780 | RSS: Main: 261832704 07:40:57 INFO - PROCESS | 4780 | 07:43:59 INFO - PROCESS | 4780 | Cycle 1(1): loaded http://localhost:50022/tests/dromaeo/cssquery-ext.html (next: http://localhost:50022/tests/dromaeo/cssquery-jquery.html) 07:44:00 INFO - PROCESS | 4780 | RSS: Main: 283099136 07:44:00 INFO - PROCESS | 4780 | 07:47:02 INFO - PROCESS | 4780 | Cycle 1(1): loaded http://localhost:50022/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50022/tests/dromaeo/cssquery-mootools.html) 07:47:03 INFO - PROCESS | 4780 | RSS: Main: 218984448 07:47:03 INFO - PROCESS | 4780 | 07:50:05 INFO - PROCESS | 4780 | Cycle 1(1): loaded http://localhost:50022/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50022/tests/dromaeo/cssquery-prototype.html) 07:50:06 INFO - PROCESS | 4780 | RSS: Main: 219824128 07:50:06 INFO - PROCESS | 4780 | 07:53:09 INFO - PROCESS | 4780 | Cycle 1(1): loaded http://localhost:50022/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50022/tests/dromaeo/cssquery-yui.html) 07:53:10 INFO - PROCESS | 4780 | RSS: Main: 221253632 07:53:10 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | Cycle 1(1): loaded http://localhost:50022/tests/dromaeo/cssquery-yui.html (next: http://localhost:50022/tests/dromaeo/cssquery-dojo.html) 07:56:13 INFO - PROCESS | 4780 | RSS: Main: 311058432 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | __start_tp_report 07:56:13 INFO - PROCESS | 4780 | _x_x_mozilla_page_load 07:56:13 INFO - PROCESS | 4780 | _x_x_mozilla_page_load_details 07:56:13 INFO - PROCESS | 4780 | |i|pagename|runs| 07:56:13 INFO - PROCESS | 4780 | |0;dojo.html;2629.552975994081;2631.5131700635384;2632.8551929643886;2669.2392668089587;2680.745329193726;4507.954920450795;4582.633389328854;4715.1512727709;4800.711957282561;4850.63620228483;4643.605293550052;4653.441587009555;4670.673052886299;4672.579467847893;4674.1352849722825;4294.248506511363;4368.126374725068;4378.846740364088;4385.144896745136;4401.647868170555;4393.07745373472;4427.822887084529;4428.047969686517;4438.112377524491;4450.087732014929;4318.006858422565;4508;4511.390962220107;4516.36770852081;4518.051209246062;4158.688098392633;4330.155619654169;4343.196508645895;4380.167768124046;4401.779911004437;4168.812403441852;4247.638950689196;4270.658347332206;4295.420118284054;4318.546552611993;4631.976840115778;4651.069786042771;4658.138244424792;4750.762461876927;4810.230363141915;5032.874178145539;5072.923906141411;5085.618578606583;5087.923681144785;5156.278121063054;4841.830535931261;5035.370578677665;5084.211947148161;5103.515166059218;5117.053345131163;4740.19416699162;4745.240761478216;4755.191617425012;4757.9286310705365;4769.928451073237;4934.876628084255;5214.921776173361;5217.843464696065;5223.164293712987;5249.448807875118;4883.072216278895;4887.755612219375;4892.975535122301;4893.266010098514;4965.056639238605;4966.15575352191;4985.651004429727;4996.800127994839;5003.5997120230295;5068.391792984866;4746.43042834862;4945.579625731853;4957.058158949788;4968.105740966661;5029.7736601853;4784.521547845187;4828.299896514985;4832.985073134681;4844.660873738875;4855.077535268288;4092.6112019358116;4136.131412403369;4137.358709400048;4143.8964025898995;4144;3718.4236443351324;3721.6650501455;3754.887353379403;3756.9248615027545;3758.3235017697075;4323.156984388077;4327.2643650579475;4378.64970802335;4381.671374646914;4389.034412429261;5045.949540504622;5052.368453943257;5058.140116180258;5058.54473097423;5124.077666020152;4149.149424368058;4342.782860856944;4343.066240758252;4346.348047792856;4359.17175736609;4554.567316104964;4594.173048851172;4610;4612.100640375162;4667.8599642010795;4769.070031343855;4776.068666610047;4781.235002399739;4781.713097214179;4844.588210002189;4780.545848144421;5084.491550844886;5093.567046800915;5099.490050994871;5162.457941916195;4533.274676051816;4740.170470167775;4789.042191561772;4809.2305231162845;4820.5420485055265;5016.046951079284;5021.07110184617;5022.347094877642;5034.0686972910125;5077.238414237894;5056.241563765318;5065.392152941523;5067.290579318825;5068.569171620454;5071.112555302881;4715.693479923794;4840.370751802243;4908.337374454403;4921.163402221702;4965.081459929925;4897.620641757177;4903.681260718042;4905.950940490549;4906.901861962812;4954.455009948831;4830.541098595628;4833.371661683958;4841.1285968526;4903.190973489334;4909.0672772173175;4664.906701866012;4901.264810278487;4972.254161875748;4974.353334066693;5042.168042273131;4839.806407743651;4874.268859671078;4875.219964805614;4878.512148785235;4934.777934993006 07:56:13 INFO - PROCESS | 4780 | |1;ext.html;551.7461967494952;559.580314763927;566.5694072504895;569.5272923473517;570.3042288408141;28196.85901570492;30690.232744181405;30718.846405767967;30740.69259307406;30742.231444213907;30137.397252054947;30787.538186927213;38102.428463573;39037;39502.20995580087;7357.926420735791;7476.214997425273;7480.77557673268;7481.476296659236;7487.10154781427;7457.81355466115;7553.69785208591;7558.773236802877;7559.659815308324;7560.659770310322;7567.432442566802;7608.467407281492;7610.467267291292;7614.428917831157;7614.73348432805;7545.434092443062;7581.772546823604;7585.2414758524255;7589.279018493289;7594.8481030379635;7629.427792915553;7682.231776822329;7686.500377475502;7693.692252309901;7695.268949449849;37064.62935370639;37327.62672373269;37461.62538374608;37510.62489375126;37593.624063759285;2992.5511173323957;3468.5664291963503;3527.4532447470683;3733.981330093332;3770.6417890300377;28366;28614.856925715445;28626.284342891387;29521.114366569036;29709.851450742608;3190.6649801770714;3232.6444091149956;3269.885554005622;3306.470964645645;3375.1055970167927;239252;241306;241558;243463;243782;30907.536386954213;49275;49948.50051499438;49960.75019624879;49965;33134.3373132536;33226.3354732904;33227.3354532908;33275;33287.83356083204;13768.449262029406;13899;13901.235432051335;13901.930490347484;13904;11443.141764367705;11516.1362897783;11516.30902145874;11518.251313664585;11520;7669.5398276103615;7703.3067023968115;7785.3771698264;7785.3771698264;7786.065672119382;3028.9091327260217;3062.4181405532877;3070.1557071805432;3071.8771249149786;3107.207662046164;855.8930133733284;870.3515880668942;880.6873559886276;934.5047125023748;991.1476130527741;27115.593266101023;27602.309942251606;27610.585841212396;28030.159095227176;28139.296517586816;932.6735642525198;935.5743136872843;950.5817440326234;977.5503268496429;993.2600212841335;14645;14805.481808136663;14954;15309.08145511273;15365.615859603377;367250;373173;373635;374936;375161;49227.75386123046;49922.251166232534;49960;50004;50070.499295007314;1088.5863371918779;1173.3781096019125;1176.8999635030948;1216.5863606373698;1217.8903898649341;26891;27875.16374508768;28358.00746973846;28798.424031518833;28843.134705958855;40464.1907161861;40968;41031.38452923149;41085.58914410818;41117.383239252034;284720;284808.60765569145;285629;286175;287045;21880.45298867541;24644.289128196244;39161;40439.595604044756;40553.797231013654;40242.79878600706;42511.78744106383;42782.14435711206;42955.7852210737;43653.12693746044;36053.45919811257;36941.815290923376;37005.629943700216;37243.62756372402;37294.44058339181;36454.27091458209;36565.634343657286;36614.63385366112;36683.44974825326;37503.249935000604;36182.63817361898;36352.63647363493;37267.254654907294;37326.25347493089;37336.06659833526;28979.710202898543;33305;33817;34099.488507672904;34166.487502687974;25028.24915252545;25426.364340890887;25634.230973070837;26045.348866278495;26069.73930260673 07:56:13 INFO - PROCESS | 4780 | |2;jquery.html;338.92577426151956;359.5361983041876;359.5882714292135;365.24576749013295;383.1570544801436;12593.811092833603;14880.627984300398;16412.26144823483;16568.58578535537;16633.667326653474;16539.751903721455;17230.396936107238;17237.741433878502;17347.566310842234;17436;4911.926321105195;5099.4900509949075;5123.846284611468;5125.103106956286;5134.768935397916;3605.531280933475;5036.622253331015;5109.642325037249;5121.692698438087;5124.461931497195;5539.196816461611;5776.942230577703;5819.476247137757;5900.616459930112;5938.970305148489;5707.058335374658;5842.620229685057;5904.439078287556;5907.409259074101;5909.054551271819;5170.482951704837;5172.250023746536;5172.922406163911;5176.767045482962;5180.326557547494;20213.797862021485;20318.593628127503;20668.17327306906;20892.37322880316;21023.369298921058;1855.6103218324033;1858.135966775456;1887.5469887226966;1911.5698967732287;2347.694799676048;12611.74776504474;13517.188968661912;13733.519326823513;13737.656558586015;14151.787723184161;1717.1757556372886;1734.358287433645;1798.3436045843305;1806.9006204658738;1812.900290484023;65086.674566627335;65685.3431465684;66887.66556167188;68492.65753671249;68896.6555167221;9363.765905852408;9551.570179341947;9669.17811985975;9729.367591106555;9975.551100200364;13071.869281307256;13213.867861321456;13230.404631791593;13253.536126235536;13254.40355184019;7527.209642987517;7577.924220757832;7626.733064342721;7768.961155194188;7794;7270.163931147986;7346.1551921528635;7507.474476786681;7522.210167932289;7572.583507907118;5992;6090.543209259324;6157.7229024694;6163.19878415803;6219.626822390671;1701.6936951348628;1703.8551723103676;1726.5597272695381;1761.5684157381268;1764.5500397398582;815.8980127484065;822.8847961285425;870.7257213977704;880.6609455359604;898.5821592959305;16659.58605936699;16853.15734213309;16899;17162.141892905303;17584.912075439795;856.6659002988839;882.4087861133056;888.1606881496923;896.7309807057857;912.3066469483108;9883.950580247196;9931.751706207402;10076.244281678903;10168.33905796121;10207.438590877573;349851;354137;355479;355709;356449;65534.344656553774;65713;66326.0051099234;67474;69996;865.4807115730512;879.2745984562762;887.9644814207488;891.7235656946367;900.5857305639348;15068.773968390702;15165.09009459436;15472.84527154759;15749.68500629958;15989.360425583312;33450.49824252687;34131.82934085314;34295.31409371749;34506.48240276448;34633;47476.52523474721;48356.516434835205;53065.017335958;53732.44163786826;56731;10400.739981500523;11180.440977951233;11830.940845296063;12081.75836483283;12227.388630568614;12945.09384343087;13232.470701171846;13245.271510066654;13395.397207125898;13491.460341586619;11318.94340528292;11372.20394572372;11409.543618255177;11617.244879082833;11940.880591193976;11304.434778261219;11364.772704545698;11521.135914806255;11587.826182607436;11733;11435;11682.47428865686;11708.590199342982;11710.238834475731;11908.52365905354;10816.621418250323;11041.83437248458;11147.944260278648;11417.657470275904;11607.76784464323;10545.472726363805;10588.576456941944;10834.133269338276;10852;11026.944865275944 07:56:13 INFO - PROCESS | 4780 | |3;mootools.html;1194.522191123551;1284.832971713677;1349.3051078694475;1376.7453021191081;1693.6274019715665;7551.584662843541;7930.643121059552;7942.00724909387;8171.795705107375;8181.386396020307;7990.480618759777;8127.390445716565;8128;8129.146439623843;8138.552379619118;1857.4149143019972;2112.9894350528302;2204.3937917072813;2228.9108435662556;2229.1863469833556;2201.757806641268;2231.8214542836618;2232.6427771556555;2234.553089382122;2237.127520267089;2181.4001149683804;2182.9781702183013;2197.7252843394576;2197.813185879202;2237.3847192022163;2096.423483542038;2127.319257837493;2137.7648458669532;2139.2619546256674;2186.0381432169793;2153.138744502196;2162.3945295317335;2170.7720689327552;2173.7500187478486;2190.7152070230927;8035.075966263896;8074.475159114698;8102.716404925799;8107.270345668859;8153.8776918346275;4510.594046535794;4652;4659.603933665609;4661.091087237956;4661.836835710767;2840.701726318748;2892.450434417454;3014.9849250753823;3017.773666974964;3017.8792848286043;972;977.0913050862695;990.6186118344417;995.8058178655165;1002.6240159940022;10964.671059868137;10968.012878840862;10971.122310215243;11079.335239885633;11082.667519974413;3919.294526985113;3986.760794352348;4084.326086195804;4109.4246805447265;4131.318332475108;8517.914820851836;8523.531205783618;8529.360297977677;8530.65877364911;8538.615762290712;4605.976970115195;4607.723536587815;4608.585227329557;4611.331356953223;4612.630989520831;3621.6016238213774;4117.135401565704;4208.22147902639;4281.143771245764;4301.41930839339;3419.46998215277;3474.652534746505;3497.108237399447;3569.982150089285;3605.9098522537147;1037.4812593703148;1052.7999680060764;1058.8623478947688;1064.435849000031;1076.9892301077045;705.8200158959432;743.9218882017418;773.9110002349689;853.8036251662153;888.5379602606608;5328.227407025959;5444.5916556258435;5467.480589344006;5592.077307244259;5623.325200975909;405.6531665426077;410.94452248946607;411.3788179848391;411.88879002669114;413.86549371454396;3809.790461524643;3918.9804050980133;3956.7032472564674;4001.419794129893;4315.611594956469;9488.193503552136;9654.179394751385;9671.951640241754;9689.12797848197;9690.51547422626;9493.762655933657;9631.133198012072;9638.807223855623;9641.517924103768;9662.13040826329;7137.89293160603;7453.180150183475;7453.813654658677;7488;7707.075150981918;5727.541796656341;5875.177475153568;5878.911816322673;5884.47039766423;5899.46904778572;9046.638134474548;9081.182693557612;9296.442213467213;9330.673426430043;9345.15893569582;9168.129027742354;9534.809303814023;9536.618535258513;9543.141117299476;9580.18568421692;6145.877082458415;6178.073289006685;6356.459700925473;7037.366637002694;7164.2119366868565;7725;7893.250141236574;7918.049834019955;7940.086890007723;7949.48328358678;6977.511574189758;7110.182329032227;7134;7142;7290.817729556591;6808.080909077211;6840.931590684029;6841.965790171017;6868.107146071178;6870.965645171742;6396.552241343062;6464.870702586016;6466.644334561644;6474.611523308617;6475.158229430144;4512.706674066175;4538.909221815611;4592.931106033479;4677;4767.6662633615315;4578.633709303181;4638.095571363687;4677.555632214934;4768.976155119202;4799.976000120117 07:56:13 INFO - PROCESS | 4780 | |4;prototype.html;286.89241534424593;294.9675535691074;300.5131686667599;300.5626812987104;302.19766519889686;4764.998700545771;4788.7126772393685;4798.208295631221;4799.448063472697;4809.711417314964;4667.696599721015;4697.248440249561;4749.4775574686755;4753;4755.785989630466;3762.9623703763023;3763.9059023524424;3793.103448275862;3797.3924172132465;3804.9429258561145;3485.163560745428;3785.3943369060958;3787.4508196311517;3802.1939578700035;3812.8474861005525;3655.689266412358;3670.6329367063213;3675.779453232801;3679.319325924699;3708.7033037357082;3641.1066245552297;3645.6718895299286;3649.434337677664;3649.671529562352;3662.5238718966366;3042.1786117748306;3092.567040614316;3193.2655489237372;3224.274538228903;3234.36929798687;4526.569975852289;4539.863804085882;4542.4776150742755;4564.566366195206;4567.748773817439;4173.582641735802;4181.330987042059;4184;4185.644220241253;4197.97901010493;4655.7439340836245;4757.786764375097;4929.654924155275;4937.629677774145;4972.279019542182;3473.722102231841;3489.9302013959577;3494.650534946485;3497.9825100874586;3502.861569989738;5095.592352611782;5120.513551212592;5145.819896303648;5155.536001759822;5161.5870730341485;3379.3610099101916;3552.0942159749616;3585.982070089685;3587.282543491312;3587.6053634100226;3579.3736096183065;3600.7299452541165;3608.404613238839;3623.5289412376223;3649.2154186849953;4140.254754144223;4149.8962525937095;4150.875473735793;4153.896152596149;4155.085881106152;4926.45808961014;4936.234883593049;4942.159832828428;4950.603951683928;4964.1809101498575;1225.8232097186801;1249.188027781949;1250.4873002069107;1259.6347059352868;1260.4517035089766;800.3277247112453;803.8585209003229;818.5129847740585;823.9670413183405;827.7185756842703;3973.4039894016128;3989.5412027616617;4010.258102251093;4030.717849750489;4038.4749982502085;4574.176648203355;4603.286490593963;4634.72191668501;4644.442666879996;4653.7207767534055;4010.438538604598;4019.7588144711412;4023.8994025149605;4030.7101727446934;4035.5762644922447;2376.9286921392386;2432.391902024494;2451.852888826676;2453.214971209196;2475.7029156501335;4279.529251782302;4374.041492449339;4581.74800385982;4680.929786053144;4775.925416781266;5182.559482444034;5206.5314121729225;5212.947870521322;5219.3736751589295;5265.473452654704;4829.251466022772;4834.468208497133;4834.564889159992;4839.322494850724;4847.854564363075;4928.728919909439;5086;5099.388073431212;5101.132807422821;5142.485751424828;4982.327385802871;4986.6010719143105;4991.675541089818;5018.411835537609;5022.698638081727;4828.2618257428;4835.8065677372515;4841.685290456109;4844.176489996638;4846.61227101824;3463.1515278756865;3618.16782140104;3634.382155033598;3643.5809881862865;3663.7618554793853;5125.7185703574105;5130.333056702754;5136.563392111712;5141.640085194001;5163.690178589297;4997.425296090851;4997.950020499894;4998.575121114601;5003.299538064601;5020.573251273682;4828.758562071953;4931.728754918514;4951.752412379438;4976.2286845539;5008.4974507648285;4883.633727470383;4897.853064408074;4908.705477671351;4913.828016019422;4918.508149185053;4955.82654607087;5014.194889839582;5048.318477005557;5055.292259083658;5064.5441910228255;4694.976525117352;4889.779959901742;4912.287718280758;4920.6801557898625;4928.310036594808 07:56:13 INFO - PROCESS | 4780 | |5;yui.html;613.7544982007197;658.8404408241496;684.9006894000369;687.8177283020001;744.9441291903108;12632.178908370961;13289.136206146593;13886.097403668768;13894.722105557896;14118.57644270671;12851.421686024129;13123.868761312408;13125.146865453762;13142;13142.47430102797;613.5030625193607;732.7142414458366;749.6176949755636;751.868423025971;751.8984937033485;742.2429122295256;768.4543973778624;772.2779201446665;775.6703401054557;775.685847231872;810.8094597769519;812.4921923797626;813.2355585749376;813.5810057820228;813.7558732380292;635.9141515895341;805.3275514945028;805.9153788852174;806.052887856766;806.6732973145827;566.5241197394208;581.6451964301773;583.2272239282925;585.4789237578558;585.976560937562;12803.807942880865;12804.743905121939;12816.871831281662;12834.550790722373;12844.871551284461;10716.946415267874;11310.434478276136;11418.828717569244;11492.367919764409;11678.708032299175;11563.76872462546;11612.825807612893;11690.623270562592;11709.765804683944;11855.822162667568;4062.1266427718183;4067.7559346439307;4129.442525259082;4272.2950713132295;4290.163418133465;64426.67786661084;64881.351186488006;65057.67471162661;65315;65869.67065164691;9641.807163856683;9647.372920760128;9655.275854310954;9765.707028789147;9876.111149996392;19199.424017279503;19227.519312017033;19241.326553570558;19251;19261.518462038282;6638.137042184485;6689.933100669028;6710.362515561014;6721.084490920064;6734.158230221223;3362.529245905575;3399.6770306820813;3485.3726329260976;3524.8590056397456;3586.318599466093;2709.498742732601;2788.581712743064;2801.90193343232;2803.4252978139434;2811.789115816281;497.72625056218936;505.58036829431484;509.06586413930114;509.1980131293228;517.9067767801832;530.1808705549935;546.6486765347876;569.1008207032878;569.8375962850567;574.3882764855416;7156.713731450788;7597.278258565428;8189;8281.254687078192;8374.623141958504;433.62274820905384;465.47168963226983;505.507130547713;524.8871492629103;555.9388467268598;3716.201016781405;3755.4366844972924;3767.830447629863;3891.474650922146;3929.4302326162547;54542.727286364105;55670;83816.58091709502;85957.57021214854;101017;76454.617726911;90519;93761.53119234496;95177;97191.51404243075;50862;70257.46138079227;81070;81784;82658.04051756914;8462.280706140047;8594.828103438022;8921.420107692978;8989.775255618662;9043.819123617359;48575.51424485851;48825;48835.75582122209;49878.25182622191;50396.49603503918;60177.69911150591;68058.65970670313;68190.3180968184;68198;70007;11967.700807479883;11975.76048479008;12127.939360303142;12296.569620063256;12359.876401236232;12696.6825829355;13006.50425201114;13035.674108147372;13133.934330328288;13354.265515396746;12679.619611411672;12763.298018609066;12784.48862045545;12790.296533690736;12841.486340546648;11908.76182476363;12616;12747.61757147287;12816.487340506275;13472.46110155622;11657.533698652298;11795.764084718086;11828.763424731285;11971.16201865861;12748.681282968;9648.75878103053;9954;10061.798764024825;10120.234487158954;10470.895291046992;9030.774230644287;9042.231410330196;9856.767904011998;10033.598656053968;10092.14216791581 07:56:13 INFO - PROCESS | 4780 | __end_tp_report 07:56:13 INFO - PROCESS | 4780 | __start_cc_report 07:56:13 INFO - PROCESS | 4780 | _x_x_mozilla_cycle_collect,1104 07:56:13 INFO - PROCESS | 4780 | __end_cc_report 07:56:13 INFO - PROCESS | 4780 | __startTimestamp1472223373320__endTimestamp 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | ------- Summary: start ------- 07:56:13 INFO - PROCESS | 4780 | Number of tests: 6 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | [#0] dojo.html Cycles:165 Average:4654.38 Median:4784.52 stddev:486.20 (10.2%) stddev-sans-first:461.02 07:56:13 INFO - PROCESS | 4780 | Values: 2629.6 2631.5 2632.9 2669.2 2680.7 4508.0 4582.6 4715.2 4800.7 4850.6 4643.6 4653.4 4670.7 4672.6 4674.1 4294.2 4368.1 4378.8 4385.1 4401.6 4393.1 4427.8 4428.0 4438.1 4450.1 4318.0 4508.0 4511.4 4516.4 4518.1 4158.7 4330.2 4343.2 4380.2 4401.8 4168.8 4247.6 4270.7 4295.4 4318.5 4632.0 4651.1 4658.1 4750.8 4810.2 5032.9 5072.9 5085.6 5087.9 5156.3 4841.8 5035.4 5084.2 5103.5 5117.1 4740.2 4745.2 4755.2 4757.9 4769.9 4934.9 5214.9 5217.8 5223.2 5249.4 4883.1 4887.8 4893.0 4893.3 4965.1 4966.2 4985.7 4996.8 5003.6 5068.4 4746.4 4945.6 4957.1 4968.1 5029.8 4784.5 4828.3 4833.0 4844.7 4855.1 4092.6 4136.1 4137.4 4143.9 4144.0 3718.4 3721.7 3754.9 3756.9 3758.3 4323.2 4327.3 4378.6 4381.7 4389.0 5045.9 5052.4 5058.1 5058.5 5124.1 4149.1 4342.8 4343.1 4346.3 4359.2 4554.6 4594.2 4610.0 4612.1 4667.9 4769.1 4776.1 4781.2 4781.7 4844.6 4780.5 5084.5 5093.6 5099.5 5162.5 4533.3 4740.2 4789.0 4809.2 4820.5 5016.0 5021.1 5022.3 5034.1 5077.2 5056.2 5065.4 5067.3 5068.6 5071.1 4715.7 4840.4 4908.3 4921.2 4965.1 4897.6 4903.7 4906.0 4906.9 4954.5 4830.5 4833.4 4841.1 4903.2 4909.1 4664.9 4901.3 4972.3 4974.4 5042.2 4839.8 4874.3 4875.2 4878.5 4934.8 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | [#1] ext.html Cycles:180 Average:44517.12 Median:202095.91 stddev:80317.82 (39.7%) stddev-sans-first:80475.30 07:56:13 INFO - PROCESS | 4780 | Values: 551.7 559.6 566.6 569.5 570.3 28196.9 30690.2 30718.8 30740.7 30742.2 30137.4 30787.5 38102.4 39037.0 39502.2 7357.9 7476.2 7480.8 7481.5 7487.1 7457.8 7553.7 7558.8 7559.7 7560.7 7567.4 7608.5 7610.5 7614.4 7614.7 7545.4 7581.8 7585.2 7589.3 7594.8 7629.4 7682.2 7686.5 7693.7 7695.3 37064.6 37327.6 37461.6 37510.6 37593.6 2992.6 3468.6 3527.5 3734.0 3770.6 28366.0 28614.9 28626.3 29521.1 29709.9 3190.7 3232.6 3269.9 3306.5 3375.1 239252.0 241306.0 241558.0 243463.0 243782.0 30907.5 49275.0 49948.5 49960.8 49965.0 33134.3 33226.3 33227.3 33275.0 33287.8 13768.4 13899.0 13901.2 13901.9 13904.0 11443.1 11516.1 11516.3 11518.3 11520.0 7669.5 7703.3 7785.4 7785.4 7786.1 3028.9 3062.4 3070.2 3071.9 3107.2 855.9 870.4 880.7 934.5 991.1 27115.6 27602.3 27610.6 28030.2 28139.3 932.7 935.6 950.6 977.6 993.3 14645.0 14805.5 14954.0 15309.1 15365.6 367250.0 373173.0 373635.0 374936.0 375161.0 49227.8 49922.3 49960.0 50004.0 50070.5 1088.6 1173.4 1176.9 1216.6 1217.9 26891.0 27875.2 28358.0 28798.4 28843.1 40464.2 40968.0 41031.4 41085.6 41117.4 284720.0 284808.6 285629.0 286175.0 287045.0 21880.5 24644.3 39161.0 40439.6 40553.8 40242.8 42511.8 42782.1 42955.8 43653.1 36053.5 36941.8 37005.6 37243.6 37294.4 36454.3 36565.6 36614.6 36683.4 37503.2 36182.6 36352.6 37267.3 37326.3 37336.1 28979.7 33305.0 33817.0 34099.5 34166.5 25028.2 25426.4 25634.2 26045.3 26069.7 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | [#2] jquery.html Cycles:180 Average:23458.60 Median:34213.57 stddev:58353.38 (170.6%) stddev-sans-first:58491.26 07:56:13 INFO - PROCESS | 4780 | Values: 338.9 359.5 359.6 365.2 383.2 12593.8 14880.6 16412.3 16568.6 16633.7 16539.8 17230.4 17237.7 17347.6 17436.0 4911.9 5099.5 5123.8 5125.1 5134.8 3605.5 5036.6 5109.6 5121.7 5124.5 5539.2 5776.9 5819.5 5900.6 5939.0 5707.1 5842.6 5904.4 5907.4 5909.1 5170.5 5172.3 5172.9 5176.8 5180.3 20213.8 20318.6 20668.2 20892.4 21023.4 1855.6 1858.1 1887.5 1911.6 2347.7 12611.7 13517.2 13733.5 13737.7 14151.8 1717.2 1734.4 1798.3 1806.9 1812.9 65086.7 65685.3 66887.7 68492.7 68896.7 9363.8 9551.6 9669.2 9729.4 9975.6 13071.9 13213.9 13230.4 13253.5 13254.4 7527.2 7577.9 7626.7 7769.0 7794.0 7270.2 7346.2 7507.5 7522.2 7572.6 5992.0 6090.5 6157.7 6163.2 6219.6 1701.7 1703.9 1726.6 1761.6 1764.6 815.9 822.9 870.7 880.7 898.6 16659.6 16853.2 16899.0 17162.1 17584.9 856.7 882.4 888.2 896.7 912.3 9884.0 9931.8 10076.2 10168.3 10207.4 349851.0 354137.0 355479.0 355709.0 356449.0 65534.3 65713.0 66326.0 67474.0 69996.0 865.5 879.3 888.0 891.7 900.6 15068.8 15165.1 15472.8 15749.7 15989.4 33450.5 34131.8 34295.3 34506.5 34633.0 47476.5 48356.5 53065.0 53732.4 56731.0 10400.7 11180.4 11830.9 12081.8 12227.4 12945.1 13232.5 13245.3 13395.4 13491.5 11318.9 11372.2 11409.5 11617.2 11940.9 11304.4 11364.8 11521.1 11587.8 11733.0 11435.0 11682.5 11708.6 11710.2 11908.5 10816.6 11041.8 11147.9 11417.7 11607.8 10545.5 10588.6 10834.1 10852.0 11026.9 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | [#3] mootools.html Cycles:180 Average:5221.87 Median:5064.10 stddev:2975.16 (58.8%) stddev-sans-first:2968.11 07:56:13 INFO - PROCESS | 4780 | Values: 1194.5 1284.8 1349.3 1376.7 1693.6 7551.6 7930.6 7942.0 8171.8 8181.4 7990.5 8127.4 8128.0 8129.1 8138.6 1857.4 2113.0 2204.4 2228.9 2229.2 2201.8 2231.8 2232.6 2234.6 2237.1 2181.4 2183.0 2197.7 2197.8 2237.4 2096.4 2127.3 2137.8 2139.3 2186.0 2153.1 2162.4 2170.8 2173.8 2190.7 8035.1 8074.5 8102.7 8107.3 8153.9 4510.6 4652.0 4659.6 4661.1 4661.8 2840.7 2892.5 3015.0 3017.8 3017.9 972.0 977.1 990.6 995.8 1002.6 10964.7 10968.0 10971.1 11079.3 11082.7 3919.3 3986.8 4084.3 4109.4 4131.3 8517.9 8523.5 8529.4 8530.7 8538.6 4606.0 4607.7 4608.6 4611.3 4612.6 3621.6 4117.1 4208.2 4281.1 4301.4 3419.5 3474.7 3497.1 3570.0 3605.9 1037.5 1052.8 1058.9 1064.4 1077.0 705.8 743.9 773.9 853.8 888.5 5328.2 5444.6 5467.5 5592.1 5623.3 405.7 410.9 411.4 411.9 413.9 3809.8 3919.0 3956.7 4001.4 4315.6 9488.2 9654.2 9672.0 9689.1 9690.5 9493.8 9631.1 9638.8 9641.5 9662.1 7137.9 7453.2 7453.8 7488.0 7707.1 5727.5 5875.2 5878.9 5884.5 5899.5 9046.6 9081.2 9296.4 9330.7 9345.2 9168.1 9534.8 9536.6 9543.1 9580.2 6145.9 6178.1 6356.5 7037.4 7164.2 7725.0 7893.3 7918.0 7940.1 7949.5 6977.5 7110.2 7134.0 7142.0 7290.8 6808.1 6840.9 6842.0 6868.1 6871.0 6396.6 6464.9 6466.6 6474.6 6475.2 4512.7 4538.9 4592.9 4677.0 4767.7 4578.6 4638.1 4677.6 4769.0 4800.0 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | [#4] prototype.html Cycles:180 Average:4067.30 Median:4639.58 stddev:1200.42 (25.9%) stddev-sans-first:1169.77 07:56:13 INFO - PROCESS | 4780 | Values: 286.9 295.0 300.5 300.6 302.2 4765.0 4788.7 4798.2 4799.4 4809.7 4667.7 4697.2 4749.5 4753.0 4755.8 3763.0 3763.9 3793.1 3797.4 3804.9 3485.2 3785.4 3787.5 3802.2 3812.8 3655.7 3670.6 3675.8 3679.3 3708.7 3641.1 3645.7 3649.4 3649.7 3662.5 3042.2 3092.6 3193.3 3224.3 3234.4 4526.6 4539.9 4542.5 4564.6 4567.7 4173.6 4181.3 4184.0 4185.6 4198.0 4655.7 4757.8 4929.7 4937.6 4972.3 3473.7 3489.9 3494.7 3498.0 3502.9 5095.6 5120.5 5145.8 5155.5 5161.6 3379.4 3552.1 3586.0 3587.3 3587.6 3579.4 3600.7 3608.4 3623.5 3649.2 4140.3 4149.9 4150.9 4153.9 4155.1 4926.5 4936.2 4942.2 4950.6 4964.2 1225.8 1249.2 1250.5 1259.6 1260.5 800.3 803.9 818.5 824.0 827.7 3973.4 3989.5 4010.3 4030.7 4038.5 4574.2 4603.3 4634.7 4644.4 4653.7 4010.4 4019.8 4023.9 4030.7 4035.6 2376.9 2432.4 2451.9 2453.2 2475.7 4279.5 4374.0 4581.7 4680.9 4775.9 5182.6 5206.5 5212.9 5219.4 5265.5 4829.3 4834.5 4834.6 4839.3 4847.9 4928.7 5086.0 5099.4 5101.1 5142.5 4982.3 4986.6 4991.7 5018.4 5022.7 4828.3 4835.8 4841.7 4844.2 4846.6 3463.2 3618.2 3634.4 3643.6 3663.8 5125.7 5130.3 5136.6 5141.6 5163.7 4997.4 4998.0 4998.6 5003.3 5020.6 4828.8 4931.7 4951.8 4976.2 5008.5 4883.6 4897.9 4908.7 4913.8 4918.5 4955.8 5014.2 5048.3 5055.3 5064.5 4695.0 4889.8 4912.3 4920.7 4928.3 07:56:13 INFO - PROCESS | 4780 | 07:56:13 INFO - PROCESS | 4780 | [#5] yui.html Cycles:180 Average:17744.31 Median:25683.79 stddev:24916.78 (97.0%) stddev-sans-first:24953.48 07:56:13 INFO - PROCESS | 4780 | Values: 613.8 658.8 684.9 687.8 744.9 12632.2 13289.1 13886.1 13894.7 14118.6 12851.4 13123.9 13125.1 13142.0 13142.5 613.5 732.7 749.6 751.9 751.9 742.2 768.5 772.3 775.7 775.7 810.8 812.5 813.2 813.6 813.8 635.9 805.3 805.9 806.1 806.7 566.5 581.6 583.2 585.5 586.0 12803.8 12804.7 12816.9 12834.6 12844.9 10716.9 11310.4 11418.8 11492.4 11678.7 11563.8 11612.8 11690.6 11709.8 11855.8 4062.1 4067.8 4129.4 4272.3 4290.2 64426.7 64881.4 65057.7 65315.0 65869.7 9641.8 9647.4 9655.3 9765.7 9876.1 19199.4 19227.5 19241.3 19251.0 19261.5 6638.1 6689.9 6710.4 6721.1 6734.2 3362.5 3399.7 3485.4 3524.9 3586.3 2709.5 2788.6 2801.9 2803.4 2811.8 497.7 505.6 509.1 509.2 517.9 530.2 546.6 569.1 569.8 574.4 7156.7 7597.3 8189.0 8281.3 8374.6 433.6 465.5 505.5 524.9 555.9 3716.2 3755.4 3767.8 3891.5 3929.4 54542.7 55670.0 83816.6 85957.6 101017.0 76454.6 90519.0 93761.5 95177.0 97191.5 50862.0 70257.5 81070.0 81784.0 82658.0 8462.3 8594.8 8921.4 8989.8 9043.8 48575.5 48825.0 48835.8 49878.3 50396.5 60177.7 68058.7 68190.3 68198.0 70007.0 11967.7 11975.8 12127.9 12296.6 12359.9 12696.7 13006.5 13035.7 13133.9 13354.3 12679.6 12763.3 12784.5 12790.3 12841.5 11908.8 12616.0 12747.6 12816.5 13472.5 11657.5 11795.8 11828.8 11971.2 12748.7 9648.8 9954.0 10061.8 10120.2 10470.9 9030.8 9042.2 9856.8 10033.6 10092.1 07:56:13 INFO - PROCESS | 4780 | -------- Summary: end -------- 07:56:13 INFO - PROCESS | 4780 | 07:56:14 INFO - TEST-INFO | 4780: exit 0 07:56:14 INFO - TEST-OK | dromaeo_css | took 1120836ms 07:56:14 INFO - TEST-START | kraken 07:56:14 INFO - operating with platform_type : linux_ 07:56:14 INFO - Initialising browser for kraken test... 07:56:14 INFO - TEST-INFO | started process 5085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3jyvh3/profile http://localhost:50022/getInfo.html) 07:56:18 INFO - PROCESS | 5085 | __metrics Screen width/height:1600/1200 07:56:18 INFO - PROCESS | 5085 | colorDepth:24 07:56:18 INFO - PROCESS | 5085 | Browser inner width/height: 1024/697 07:56:18 INFO - PROCESS | 5085 | __metrics 07:56:19 INFO - PROCESS | 5085 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:56:19 INFO - PROCESS | 5085 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:56:19 INFO - PROCESS | 5085 | console.error: PushService: 07:56:19 INFO - PROCESS | 5085 | stateChangeProcessEnqueue: Error transitioning state 07:56:19 INFO - PROCESS | 5085 | UnknownError 07:56:20 INFO - TEST-INFO | 5085: exit 0 07:56:20 INFO - Browser initialized. 07:56:20 INFO - Running cycle 1/1 for kraken test... 07:56:20 INFO - TEST-INFO | started process 5169 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3jyvh3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:56:32 INFO - PROCESS | 5169 | RSS: Main: 181366784 07:56:32 INFO - PROCESS | 5169 | 07:56:35 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50022/tests/kraken/driver.html?testName=audio-beat-detection) 07:56:36 INFO - PROCESS | 5169 | RSS: Main: 234954752 07:56:36 INFO - PROCESS | 5169 | 07:56:39 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50022/tests/kraken/driver.html?testName=audio-dft) 07:56:39 INFO - PROCESS | 5169 | RSS: Main: 225136640 07:56:39 INFO - PROCESS | 5169 | 07:56:42 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50022/tests/kraken/driver.html?testName=audio-fft) 07:56:43 INFO - PROCESS | 5169 | RSS: Main: 303841280 07:56:43 INFO - PROCESS | 5169 | 07:56:45 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50022/tests/kraken/driver.html?testName=audio-oscillator) 07:56:46 INFO - PROCESS | 5169 | RSS: Main: 213307392 07:56:46 INFO - PROCESS | 5169 | 07:56:48 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50022/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:56:49 INFO - PROCESS | 5169 | RSS: Main: 216031232 07:56:49 INFO - PROCESS | 5169 | 07:56:52 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50022/tests/kraken/driver.html?testName=imaging-darkroom) 07:56:52 INFO - PROCESS | 5169 | RSS: Main: 255008768 07:56:52 INFO - PROCESS | 5169 | 07:56:56 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50022/tests/kraken/driver.html?testName=imaging-desaturate) 07:56:56 INFO - PROCESS | 5169 | RSS: Main: 249102336 07:56:56 INFO - PROCESS | 5169 | 07:56:59 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50022/tests/kraken/driver.html?testName=json-parse-financial) 07:57:00 INFO - PROCESS | 5169 | RSS: Main: 252764160 07:57:00 INFO - PROCESS | 5169 | 07:57:02 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50022/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:57:02 INFO - PROCESS | 5169 | RSS: Main: 216121344 07:57:02 INFO - PROCESS | 5169 | 07:57:04 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:57:05 INFO - PROCESS | 5169 | RSS: Main: 224256000 07:57:05 INFO - PROCESS | 5169 | 07:57:07 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:57:08 INFO - PROCESS | 5169 | RSS: Main: 222752768 07:57:08 INFO - PROCESS | 5169 | 07:57:11 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:57:11 INFO - PROCESS | 5169 | RSS: Main: 223948800 07:57:11 INFO - PROCESS | 5169 | 07:57:14 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:57:15 INFO - PROCESS | 5169 | RSS: Main: 222351360 07:57:15 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | Cycle 1(1): loaded http://localhost:50022/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50022/tests/kraken/driver.html?testName=ai-astar) 07:57:17 INFO - PROCESS | 5169 | RSS: Main: 273395712 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | __start_tp_report 07:57:17 INFO - PROCESS | 5169 | _x_x_mozilla_page_load 07:57:17 INFO - PROCESS | 5169 | _x_x_mozilla_page_load_details 07:57:17 INFO - PROCESS | 5169 | |i|pagename|runs| 07:57:17 INFO - PROCESS | 5169 | |0;ai-astar;116;115;131;117;114;115;115;115;114;113 07:57:17 INFO - PROCESS | 5169 | |1;audio-beat-detection;153;122;157;180;130;147;155;125;187;122 07:57:17 INFO - PROCESS | 5169 | |2;audio-dft;163;162;164;160;160;162;162;163;162;162 07:57:17 INFO - PROCESS | 5169 | |3;audio-fft;76;98;77;158;78;80;76;77;160;77 07:57:17 INFO - PROCESS | 5169 | |4;audio-oscillator;84;84;146;84;82;83;147;83;83;143 07:57:17 INFO - PROCESS | 5169 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;117 07:57:17 INFO - PROCESS | 5169 | |6;imaging-darkroom;147;147;147;148;147;147;147;149;147;147 07:57:17 INFO - PROCESS | 5169 | |7;imaging-desaturate;94;95;95;94;95;94;94;95;94;95 07:57:17 INFO - PROCESS | 5169 | |8;json-parse-financial;66;63;64;64;62;66;64;65;62;64 07:57:17 INFO - PROCESS | 5169 | |9;json-stringify-tinderbox;66;66;65;66;66;65;65;65;65;66 07:57:17 INFO - PROCESS | 5169 | |10;stanford-crypto-aes;86;89;83;82;86;82;82;82;83;82 07:57:17 INFO - PROCESS | 5169 | |11;stanford-crypto-ccm;136;146;130;133;134;141;141;134;171;133 07:57:17 INFO - PROCESS | 5169 | |12;stanford-crypto-pbkdf2;175;190;178;181;175;174;175;190;176;177 07:57:17 INFO - PROCESS | 5169 | |13;stanford-crypto-sha256-iterative;69;68;69;67;69;80;71;69;68;68 07:57:17 INFO - PROCESS | 5169 | __end_tp_report 07:57:17 INFO - PROCESS | 5169 | __start_cc_report 07:57:17 INFO - PROCESS | 5169 | _x_x_mozilla_cycle_collect,1983 07:57:17 INFO - PROCESS | 5169 | __end_cc_report 07:57:17 INFO - PROCESS | 5169 | __startTimestamp1472223437327__endTimestamp 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | ------- Summary: start ------- 07:57:17 INFO - PROCESS | 5169 | Number of tests: 14 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#0] ai-astar Cycles:10 Average:116.50 Median:115.00 stddev:5.21 (4.5%) stddev-sans-first:5.53 07:57:17 INFO - PROCESS | 5169 | Values: 116.0 115.0 131.0 117.0 114.0 115.0 115.0 115.0 114.0 113.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:154.00 stddev:23.35 (15.2%) stddev-sans-first:24.69 07:57:17 INFO - PROCESS | 5169 | Values: 153.0 122.0 157.0 180.0 130.0 147.0 155.0 125.0 187.0 122.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 07:57:17 INFO - PROCESS | 5169 | Values: 163.0 162.0 164.0 160.0 160.0 162.0 162.0 163.0 162.0 162.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#3] audio-fft Cycles:10 Average:95.70 Median:77.00 stddev:34.00 (44.2%) stddev-sans-first:35.31 07:57:17 INFO - PROCESS | 5169 | Values: 76.0 98.0 77.0 158.0 78.0 80.0 76.0 77.0 160.0 77.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:83.00 stddev:29.99 (36.1%) stddev-sans-first:31.11 07:57:17 INFO - PROCESS | 5169 | Values: 84.0 84.0 146.0 84.0 82.0 83.0 147.0 83.0 83.0 143.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:57:17 INFO - PROCESS | 5169 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 07:57:17 INFO - PROCESS | 5169 | Values: 147.0 147.0 147.0 148.0 147.0 147.0 147.0 149.0 147.0 147.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 07:57:17 INFO - PROCESS | 5169 | Values: 94.0 95.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:1.41 (2.2%) stddev-sans-first:1.30 07:57:17 INFO - PROCESS | 5169 | Values: 66.0 63.0 64.0 64.0 62.0 66.0 64.0 65.0 62.0 64.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 07:57:17 INFO - PROCESS | 5169 | Values: 66.0 66.0 65.0 66.0 66.0 65.0 65.0 65.0 65.0 66.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.00 stddev:2.45 (3.0%) stddev-sans-first:2.46 07:57:17 INFO - PROCESS | 5169 | Values: 86.0 89.0 83.0 82.0 86.0 82.0 82.0 82.0 83.0 82.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:138.50 stddev:11.95 (8.6%) stddev-sans-first:12.59 07:57:17 INFO - PROCESS | 5169 | Values: 136.0 146.0 130.0 133.0 134.0 141.0 141.0 134.0 171.0 133.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:177.50 stddev:6.08 (3.4%) stddev-sans-first:6.27 07:57:17 INFO - PROCESS | 5169 | Values: 175.0 190.0 178.0 181.0 175.0 174.0 175.0 190.0 176.0 177.0 07:57:17 INFO - PROCESS | 5169 | 07:57:17 INFO - PROCESS | 5169 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:69.00 stddev:3.74 (5.4%) stddev-sans-first:3.95 07:57:17 INFO - PROCESS | 5169 | Values: 69.0 68.0 69.0 67.0 69.0 80.0 71.0 69.0 68.0 68.0 07:57:17 INFO - PROCESS | 5169 | -------- Summary: end -------- 07:57:17 INFO - PROCESS | 5169 | 07:57:18 INFO - TEST-INFO | 5169: exit 0 07:57:18 INFO - TEST-OK | kraken | took 64260ms 07:57:18 INFO - SUITE-END | took 1185s 07:57:18 INFO - Completed test suite (00:19:45) 07:57:18 INFO - javascript benchmark 07:57:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2629.552975994081, 2631.5131700635384, 2632.8551929643886, 2669.2392668089587, 2680.745329193726, 4507.954920450795, 4582.633389328854, 4715.1512727709, 4800.711957282561, 4850.63620228483, 4643.605293550052, 4653.441587009555, 4670.673052886299, 4672.579467847893, 4674.1352849722825, 4294.248506511363, 4368.126374725068, 4378.846740364088, 4385.144896745136, 4401.647868170555, 4393.07745373472, 4427.822887084529, 4428.047969686517, 4438.112377524491, 4450.087732014929, 4318.006858422565, 4508.0, 4511.390962220107, 4516.36770852081, 4518.051209246062, 4158.688098392633, 4330.155619654169, 4343.196508645895, 4380.167768124046, 4401.779911004437, 4168.812403441852, 4247.638950689196, 4270.658347332206, 4295.420118284054, 4318.546552611993, 4631.976840115778, 4651.069786042771, 4658.138244424792, 4750.762461876927, 4810.230363141915, 5032.874178145539, 5072.923906141411, 5085.618578606583, 5087.923681144785, 5156.278121063054, 4841.830535931261, 5035.370578677665, 5084.211947148161, 5103.515166059218, 5117.053345131163, 4740.19416699162, 4745.240761478216, 4755.191617425012, 4757.9286310705365, 4769.928451073237, 4934.876628084255, 5214.921776173361, 5217.843464696065, 5223.164293712987, 5249.448807875118, 4883.072216278895, 4887.755612219375, 4892.975535122301, 4893.266010098514, 4965.056639238605, 4966.15575352191, 4985.651004429727, 4996.800127994839, 5003.5997120230295, 5068.391792984866, 4746.43042834862, 4945.579625731853, 4957.058158949788, 4968.105740966661, 5029.7736601853, 4784.521547845187, 4828.299896514985, 4832.985073134681, 4844.660873738875, 4855.077535268288, 4092.6112019358116, 4136.131412403369, 4137.358709400048, 4143.8964025898995, 4144.0, 3718.4236443351324, 3721.6650501455, 3754.887353379403, 3756.9248615027545, 3758.3235017697075, 4323.156984388077, 4327.2643650579475, 4378.64970802335, 4381.671374646914, 4389.034412429261, 5045.949540504622, 5052.368453943257, 5058.140116180258, 5058.54473097423, 5124.077666020152, 4149.149424368058, 4342.782860856944, 4343.066240758252, 4346.348047792856, 4359.17175736609, 4554.567316104964, 4594.173048851172, 4610.0, 4612.100640375162, 4667.8599642010795, 4769.070031343855, 4776.068666610047, 4781.235002399739, 4781.713097214179, 4844.588210002189, 4780.545848144421, 5084.491550844886, 5093.567046800915, 5099.490050994871, 5162.457941916195, 4533.274676051816, 4740.170470167775, 4789.042191561772, 4809.2305231162845, 4820.5420485055265, 5016.046951079284, 5021.07110184617, 5022.347094877642, 5034.0686972910125, 5077.238414237894, 5056.241563765318, 5065.392152941523, 5067.290579318825, 5068.569171620454, 5071.112555302881, 4715.693479923794, 4840.370751802243, 4908.337374454403, 4921.163402221702, 4965.081459929925, 4897.620641757177, 4903.681260718042, 4905.950940490549, 4906.901861962812, 4954.455009948831, 4830.541098595628, 4833.371661683958, 4841.1285968526, 4903.190973489334, 4909.0672772173175, 4664.906701866012, 4901.264810278487, 4972.254161875748, 4974.353334066693, 5042.168042273131, 4839.806407743651, 4874.268859671078, 4875.219964805614, 4878.512148785235, 4934.777934993006], "value": 4623.79201724929, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.7461967494952, 559.580314763927, 566.5694072504895, 569.5272923473517, 570.3042288408141, 28196.85901570492, 30690.232744181405, 30718.846405767967, 30740.69259307406, 30742.231444213907, 30137.397252054947, 30787.538186927213, 38102.428463573, 39037.0, 39502.20995580087, 7357.926420735791, 7476.214997425273, 7480.77557673268, 7481.476296659236, 7487.10154781427, 7457.81355466115, 7553.69785208591, 7558.773236802877, 7559.659815308324, 7560.659770310322, 7567.432442566802, 7608.467407281492, 7610.467267291292, 7614.428917831157, 7614.73348432805, 7545.434092443062, 7581.772546823604, 7585.2414758524255, 7589.279018493289, 7594.8481030379635, 7629.427792915553, 7682.231776822329, 7686.500377475502, 7693.692252309901, 7695.268949449849, 37064.62935370639, 37327.62672373269, 37461.62538374608, 37510.62489375126, 37593.624063759285, 2992.5511173323957, 3468.5664291963503, 3527.4532447470683, 3733.981330093332, 3770.6417890300377, 28366.0, 28614.856925715445, 28626.284342891387, 29521.114366569036, 29709.851450742608, 3190.6649801770714, 3232.6444091149956, 3269.885554005622, 3306.470964645645, 3375.1055970167927, 239252.0, 241306.0, 241558.0, 243463.0, 243782.0, 30907.536386954213, 49275.0, 49948.50051499438, 49960.75019624879, 49965.0, 33134.3373132536, 33226.3354732904, 33227.3354532908, 33275.0, 33287.83356083204, 13768.449262029406, 13899.0, 13901.235432051335, 13901.930490347484, 13904.0, 11443.141764367705, 11516.1362897783, 11516.30902145874, 11518.251313664585, 11520.0, 7669.5398276103615, 7703.3067023968115, 7785.3771698264, 7785.3771698264, 7786.065672119382, 3028.9091327260217, 3062.4181405532877, 3070.1557071805432, 3071.8771249149786, 3107.207662046164, 855.8930133733284, 870.3515880668942, 880.6873559886276, 934.5047125023748, 991.1476130527741, 27115.593266101023, 27602.309942251606, 27610.585841212396, 28030.159095227176, 28139.296517586816, 932.6735642525198, 935.5743136872843, 950.5817440326234, 977.5503268496429, 993.2600212841335, 14645.0, 14805.481808136663, 14954.0, 15309.08145511273, 15365.615859603377, 367250.0, 373173.0, 373635.0, 374936.0, 375161.0, 49227.75386123046, 49922.251166232534, 49960.0, 50004.0, 50070.499295007314, 1088.5863371918779, 1173.3781096019125, 1176.8999635030948, 1216.5863606373698, 1217.8903898649341, 26891.0, 27875.16374508768, 28358.00746973846, 28798.424031518833, 28843.134705958855, 40464.1907161861, 40968.0, 41031.38452923149, 41085.58914410818, 41117.383239252034, 284720.0, 284808.60765569145, 285629.0, 286175.0, 287045.0, 21880.45298867541, 24644.289128196244, 39161.0, 40439.595604044756, 40553.797231013654, 40242.79878600706, 42511.78744106383, 42782.14435711206, 42955.7852210737, 43653.12693746044, 36053.45919811257, 36941.815290923376, 37005.629943700216, 37243.62756372402, 37294.44058339181, 36454.27091458209, 36565.634343657286, 36614.63385366112, 36683.44974825326, 37503.249935000604, 36182.63817361898, 36352.63647363493, 37267.254654907294, 37326.25347493089, 37336.06659833526, 28979.710202898543, 33305.0, 33817.0, 34099.488507672904, 34166.487502687974, 25028.24915252545, 25426.364340890887, 25634.230973070837, 26045.348866278495, 26069.73930260673], "value": 16270.23561669786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.92577426151956, 359.5361983041876, 359.5882714292135, 365.24576749013295, 383.1570544801436, 12593.811092833603, 14880.627984300398, 16412.26144823483, 16568.58578535537, 16633.667326653474, 16539.751903721455, 17230.396936107238, 17237.741433878502, 17347.566310842234, 17436.0, 4911.926321105195, 5099.4900509949075, 5123.846284611468, 5125.103106956286, 5134.768935397916, 3605.531280933475, 5036.622253331015, 5109.642325037249, 5121.692698438087, 5124.461931497195, 5539.196816461611, 5776.942230577703, 5819.476247137757, 5900.616459930112, 5938.970305148489, 5707.058335374658, 5842.620229685057, 5904.439078287556, 5907.409259074101, 5909.054551271819, 5170.482951704837, 5172.250023746536, 5172.922406163911, 5176.767045482962, 5180.326557547494, 20213.797862021485, 20318.593628127503, 20668.17327306906, 20892.37322880316, 21023.369298921058, 1855.6103218324033, 1858.135966775456, 1887.5469887226966, 1911.5698967732287, 2347.694799676048, 12611.74776504474, 13517.188968661912, 13733.519326823513, 13737.656558586015, 14151.787723184161, 1717.1757556372886, 1734.358287433645, 1798.3436045843305, 1806.9006204658738, 1812.900290484023, 65086.674566627335, 65685.3431465684, 66887.66556167188, 68492.65753671249, 68896.6555167221, 9363.765905852408, 9551.570179341947, 9669.17811985975, 9729.367591106555, 9975.551100200364, 13071.869281307256, 13213.867861321456, 13230.404631791593, 13253.536126235536, 13254.40355184019, 7527.209642987517, 7577.924220757832, 7626.733064342721, 7768.961155194188, 7794.0, 7270.163931147986, 7346.1551921528635, 7507.474476786681, 7522.210167932289, 7572.583507907118, 5992.0, 6090.543209259324, 6157.7229024694, 6163.19878415803, 6219.626822390671, 1701.6936951348628, 1703.8551723103676, 1726.5597272695381, 1761.5684157381268, 1764.5500397398582, 815.8980127484065, 822.8847961285425, 870.7257213977704, 880.6609455359604, 898.5821592959305, 16659.58605936699, 16853.15734213309, 16899.0, 17162.141892905303, 17584.912075439795, 856.6659002988839, 882.4087861133056, 888.1606881496923, 896.7309807057857, 912.3066469483108, 9883.950580247196, 9931.751706207402, 10076.244281678903, 10168.33905796121, 10207.438590877573, 349851.0, 354137.0, 355479.0, 355709.0, 356449.0, 65534.344656553774, 65713.0, 66326.0051099234, 67474.0, 69996.0, 865.4807115730512, 879.2745984562762, 887.9644814207488, 891.7235656946367, 900.5857305639348, 15068.773968390702, 15165.09009459436, 15472.84527154759, 15749.68500629958, 15989.360425583312, 33450.49824252687, 34131.82934085314, 34295.31409371749, 34506.48240276448, 34633.0, 47476.52523474721, 48356.516434835205, 53065.017335958, 53732.44163786826, 56731.0, 10400.739981500523, 11180.440977951233, 11830.940845296063, 12081.75836483283, 12227.388630568614, 12945.09384343087, 13232.470701171846, 13245.271510066654, 13395.397207125898, 13491.460341586619, 11318.94340528292, 11372.20394572372, 11409.543618255177, 11617.244879082833, 11940.880591193976, 11304.434778261219, 11364.772704545698, 11521.135914806255, 11587.826182607436, 11733.0, 11435.0, 11682.47428865686, 11708.590199342982, 11710.238834475731, 11908.52365905354, 10816.621418250323, 11041.83437248458, 11147.944260278648, 11417.657470275904, 11607.76784464323, 10545.472726363805, 10588.576456941944, 10834.133269338276, 10852.0, 11026.944865275944], "value": 8605.257269078851, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1194.522191123551, 1284.832971713677, 1349.3051078694475, 1376.7453021191081, 1693.6274019715665, 7551.584662843541, 7930.643121059552, 7942.00724909387, 8171.795705107375, 8181.386396020307, 7990.480618759777, 8127.390445716565, 8128.0, 8129.146439623843, 8138.552379619118, 1857.4149143019972, 2112.9894350528302, 2204.3937917072813, 2228.9108435662556, 2229.1863469833556, 2201.757806641268, 2231.8214542836618, 2232.6427771556555, 2234.553089382122, 2237.127520267089, 2181.4001149683804, 2182.9781702183013, 2197.7252843394576, 2197.813185879202, 2237.3847192022163, 2096.423483542038, 2127.319257837493, 2137.7648458669532, 2139.2619546256674, 2186.0381432169793, 2153.138744502196, 2162.3945295317335, 2170.7720689327552, 2173.7500187478486, 2190.7152070230927, 8035.075966263896, 8074.475159114698, 8102.716404925799, 8107.270345668859, 8153.8776918346275, 4510.594046535794, 4652.0, 4659.603933665609, 4661.091087237956, 4661.836835710767, 2840.701726318748, 2892.450434417454, 3014.9849250753823, 3017.773666974964, 3017.8792848286043, 972.0, 977.0913050862695, 990.6186118344417, 995.8058178655165, 1002.6240159940022, 10964.671059868137, 10968.012878840862, 10971.122310215243, 11079.335239885633, 11082.667519974413, 3919.294526985113, 3986.760794352348, 4084.326086195804, 4109.4246805447265, 4131.318332475108, 8517.914820851836, 8523.531205783618, 8529.360297977677, 8530.65877364911, 8538.615762290712, 4605.976970115195, 4607.723536587815, 4608.585227329557, 4611.331356953223, 4612.630989520831, 3621.6016238213774, 4117.135401565704, 4208.22147902639, 4281.143771245764, 4301.41930839339, 3419.46998215277, 3474.652534746505, 3497.108237399447, 3569.982150089285, 3605.9098522537147, 1037.4812593703148, 1052.7999680060764, 1058.8623478947688, 1064.435849000031, 1076.9892301077045, 705.8200158959432, 743.9218882017418, 773.9110002349689, 853.8036251662153, 888.5379602606608, 5328.227407025959, 5444.5916556258435, 5467.480589344006, 5592.077307244259, 5623.325200975909, 405.6531665426077, 410.94452248946607, 411.3788179848391, 411.88879002669114, 413.86549371454396, 3809.790461524643, 3918.9804050980133, 3956.7032472564674, 4001.419794129893, 4315.611594956469, 9488.193503552136, 9654.179394751385, 9671.951640241754, 9689.12797848197, 9690.51547422626, 9493.762655933657, 9631.133198012072, 9638.807223855623, 9641.517924103768, 9662.13040826329, 7137.89293160603, 7453.180150183475, 7453.813654658677, 7488.0, 7707.075150981918, 5727.541796656341, 5875.177475153568, 5878.911816322673, 5884.47039766423, 5899.46904778572, 9046.638134474548, 9081.182693557612, 9296.442213467213, 9330.673426430043, 9345.15893569582, 9168.129027742354, 9534.809303814023, 9536.618535258513, 9543.141117299476, 9580.18568421692, 6145.877082458415, 6178.073289006685, 6356.459700925473, 7037.366637002694, 7164.2119366868565, 7725.0, 7893.250141236574, 7918.049834019955, 7940.086890007723, 7949.48328358678, 6977.511574189758, 7110.182329032227, 7134.0, 7142.0, 7290.817729556591, 6808.080909077211, 6840.931590684029, 6841.965790171017, 6868.107146071178, 6870.965645171742, 6396.552241343062, 6464.870702586016, 6466.644334561644, 6474.611523308617, 6475.158229430144, 4512.706674066175, 4538.909221815611, 4592.931106033479, 4677.0, 4767.6662633615315, 4578.633709303181, 4638.095571363687, 4677.555632214934, 4768.976155119202, 4799.976000120117], "value": 4098.065186116444, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.89241534424593, 294.9675535691074, 300.5131686667599, 300.5626812987104, 302.19766519889686, 4764.998700545771, 4788.7126772393685, 4798.208295631221, 4799.448063472697, 4809.711417314964, 4667.696599721015, 4697.248440249561, 4749.4775574686755, 4753.0, 4755.785989630466, 3762.9623703763023, 3763.9059023524424, 3793.103448275862, 3797.3924172132465, 3804.9429258561145, 3485.163560745428, 3785.3943369060958, 3787.4508196311517, 3802.1939578700035, 3812.8474861005525, 3655.689266412358, 3670.6329367063213, 3675.779453232801, 3679.319325924699, 3708.7033037357082, 3641.1066245552297, 3645.6718895299286, 3649.434337677664, 3649.671529562352, 3662.5238718966366, 3042.1786117748306, 3092.567040614316, 3193.2655489237372, 3224.274538228903, 3234.36929798687, 4526.569975852289, 4539.863804085882, 4542.4776150742755, 4564.566366195206, 4567.748773817439, 4173.582641735802, 4181.330987042059, 4184.0, 4185.644220241253, 4197.97901010493, 4655.7439340836245, 4757.786764375097, 4929.654924155275, 4937.629677774145, 4972.279019542182, 3473.722102231841, 3489.9302013959577, 3494.650534946485, 3497.9825100874586, 3502.861569989738, 5095.592352611782, 5120.513551212592, 5145.819896303648, 5155.536001759822, 5161.5870730341485, 3379.3610099101916, 3552.0942159749616, 3585.982070089685, 3587.282543491312, 3587.6053634100226, 3579.3736096183065, 3600.7299452541165, 3608.404613238839, 3623.5289412376223, 3649.2154186849953, 4140.254754144223, 4149.8962525937095, 4150.875473735793, 4153.896152596149, 4155.085881106152, 4926.45808961014, 4936.234883593049, 4942.159832828428, 4950.603951683928, 4964.1809101498575, 1225.8232097186801, 1249.188027781949, 1250.4873002069107, 1259.6347059352868, 1260.4517035089766, 800.3277247112453, 803.8585209003229, 818.5129847740585, 823.9670413183405, 827.7185756842703, 3973.4039894016128, 3989.5412027616617, 4010.258102251093, 4030.717849750489, 4038.4749982502085, 4574.176648203355, 4603.286490593963, 4634.72191668501, 4644.442666879996, 4653.7207767534055, 4010.438538604598, 4019.7588144711412, 4023.8994025149605, 4030.7101727446934, 4035.5762644922447, 2376.9286921392386, 2432.391902024494, 2451.852888826676, 2453.214971209196, 2475.7029156501335, 4279.529251782302, 4374.041492449339, 4581.74800385982, 4680.929786053144, 4775.925416781266, 5182.559482444034, 5206.5314121729225, 5212.947870521322, 5219.3736751589295, 5265.473452654704, 4829.251466022772, 4834.468208497133, 4834.564889159992, 4839.322494850724, 4847.854564363075, 4928.728919909439, 5086.0, 5099.388073431212, 5101.132807422821, 5142.485751424828, 4982.327385802871, 4986.6010719143105, 4991.675541089818, 5018.411835537609, 5022.698638081727, 4828.2618257428, 4835.8065677372515, 4841.685290456109, 4844.176489996638, 4846.61227101824, 3463.1515278756865, 3618.16782140104, 3634.382155033598, 3643.5809881862865, 3663.7618554793853, 5125.7185703574105, 5130.333056702754, 5136.563392111712, 5141.640085194001, 5163.690178589297, 4997.425296090851, 4997.950020499894, 4998.575121114601, 5003.299538064601, 5020.573251273682, 4828.758562071953, 4931.728754918514, 4951.752412379438, 4976.2286845539, 5008.4974507648285, 4883.633727470383, 4897.853064408074, 4908.705477671351, 4913.828016019422, 4918.508149185053, 4955.82654607087, 5014.194889839582, 5048.318477005557, 5055.292259083658, 5064.5441910228255, 4694.976525117352, 4889.779959901742, 4912.287718280758, 4920.6801557898625, 4928.310036594808], "value": 3686.832308051195, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.7544982007197, 658.8404408241496, 684.9006894000369, 687.8177283020001, 744.9441291903108, 12632.178908370961, 13289.136206146593, 13886.097403668768, 13894.722105557896, 14118.57644270671, 12851.421686024129, 13123.868761312408, 13125.146865453762, 13142.0, 13142.47430102797, 613.5030625193607, 732.7142414458366, 749.6176949755636, 751.868423025971, 751.8984937033485, 742.2429122295256, 768.4543973778624, 772.2779201446665, 775.6703401054557, 775.685847231872, 810.8094597769519, 812.4921923797626, 813.2355585749376, 813.5810057820228, 813.7558732380292, 635.9141515895341, 805.3275514945028, 805.9153788852174, 806.052887856766, 806.6732973145827, 566.5241197394208, 581.6451964301773, 583.2272239282925, 585.4789237578558, 585.976560937562, 12803.807942880865, 12804.743905121939, 12816.871831281662, 12834.550790722373, 12844.871551284461, 10716.946415267874, 11310.434478276136, 11418.828717569244, 11492.367919764409, 11678.708032299175, 11563.76872462546, 11612.825807612893, 11690.623270562592, 11709.765804683944, 11855.822162667568, 4062.1266427718183, 4067.7559346439307, 4129.442525259082, 4272.2950713132295, 4290.163418133465, 64426.67786661084, 64881.351186488006, 65057.67471162661, 65315.0, 65869.67065164691, 9641.807163856683, 9647.372920760128, 9655.275854310954, 9765.707028789147, 9876.111149996392, 19199.424017279503, 19227.519312017033, 19241.326553570558, 19251.0, 19261.518462038282, 6638.137042184485, 6689.933100669028, 6710.362515561014, 6721.084490920064, 6734.158230221223, 3362.529245905575, 3399.6770306820813, 3485.3726329260976, 3524.8590056397456, 3586.318599466093, 2709.498742732601, 2788.581712743064, 2801.90193343232, 2803.4252978139434, 2811.789115816281, 497.72625056218936, 505.58036829431484, 509.06586413930114, 509.1980131293228, 517.9067767801832, 530.1808705549935, 546.6486765347876, 569.1008207032878, 569.8375962850567, 574.3882764855416, 7156.713731450788, 7597.278258565428, 8189.0, 8281.254687078192, 8374.623141958504, 433.62274820905384, 465.47168963226983, 505.507130547713, 524.8871492629103, 555.9388467268598, 3716.201016781405, 3755.4366844972924, 3767.830447629863, 3891.474650922146, 3929.4302326162547, 54542.727286364105, 55670.0, 83816.58091709502, 85957.57021214854, 101017.0, 76454.617726911, 90519.0, 93761.53119234496, 95177.0, 97191.51404243075, 50862.0, 70257.46138079227, 81070.0, 81784.0, 82658.04051756914, 8462.280706140047, 8594.828103438022, 8921.420107692978, 8989.775255618662, 9043.819123617359, 48575.51424485851, 48825.0, 48835.75582122209, 49878.25182622191, 50396.49603503918, 60177.69911150591, 68058.65970670313, 68190.3180968184, 68198.0, 70007.0, 11967.700807479883, 11975.76048479008, 12127.939360303142, 12296.569620063256, 12359.876401236232, 12696.6825829355, 13006.50425201114, 13035.674108147372, 13133.934330328288, 13354.265515396746, 12679.619611411672, 12763.298018609066, 12784.48862045545, 12790.296533690736, 12841.486340546648, 11908.76182476363, 12616.0, 12747.61757147287, 12816.487340506275, 13472.46110155622, 11657.533698652298, 11795.764084718086, 11828.763424731285, 11971.16201865861, 12748.681282968, 9648.75878103053, 9954.0, 10061.798764024825, 10120.234487158954, 10470.895291046992, 9030.774230644287, 9042.231410330196, 9856.767904011998, 10033.598656053968, 10092.14216791581], "value": 6558.461541379833, "unit": "score"}], "name": "dromaeo_css", "value": 6327.127305578621, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 131.0, 117.0, 114.0, 115.0, 115.0, 115.0, 114.0, 113.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 157.0, 180.0, 130.0, 147.0, 155.0, 125.0, 187.0, 122.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 160.0, 160.0, 162.0, 162.0, 163.0, 162.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 98.0, 77.0, 158.0, 78.0, 80.0, 76.0, 77.0, 160.0, 77.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 146.0, 84.0, 82.0, 83.0, 147.0, 83.0, 83.0, 143.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 149.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 64.0, 64.0, 62.0, 66.0, 64.0, 65.0, 62.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 65.0, 65.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 83.0, 82.0, 86.0, 82.0, 82.0, 82.0, 83.0, 82.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 146.0, 130.0, 133.0, 134.0, 141.0, 141.0, 134.0, 171.0, 133.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 190.0, 178.0, 181.0, 175.0, 174.0, 175.0, 190.0, 176.0, 177.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 67.0, 69.0, 80.0, 71.0, 69.0, 68.0, 68.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1584.6, "alertThreshold": 2.0}]} 07:57:19 INFO - Return code: 0 07:57:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:57:19 INFO - # TBPL SUCCESS # 07:57:19 INFO - Running post-action listener: _resource_record_post_action 07:57:19 INFO - [mozharness: 2016-08-26 14:57:19.121637Z] Finished run-tests step (success) 07:57:19 INFO - Running post-run listener: _resource_record_post_run 07:57:19 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 425984; Write bytes: 419762176; Read time: 48; Write time: 255056 07:57:19 INFO - TinderboxPrint: CPU usage
13.1% 07:57:19 INFO - TinderboxPrint: I/O read bytes / time
425,984 / 48 07:57:19 INFO - TinderboxPrint: I/O write bytes / time
419,762,176 / 255,056 07:57:19 INFO - TinderboxPrint: CPU idle
8,337.7 (86.9%) 07:57:19 INFO - TinderboxPrint: CPU user
1,204.6 (12.6%) 07:57:19 INFO - TinderboxPrint: Swap in / out
0 / 0 07:57:19 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114515968; Read time: 0; Write time: 71928 07:57:19 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 425984; Write bytes: 293994496; Read time: 48; Write time: 180772 07:57:19 INFO - Running post-run listener: _upload_blobber_files 07:57:19 INFO - Blob upload gear active. 07:57:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:57:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:57:19 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'] 07:57:19 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 07:57:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:57:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:57:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:57:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:23 INFO - (blobuploader) - INFO - Done attempting. 07:57:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:57:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:57:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:24 INFO - (blobuploader) - INFO - Done attempting. 07:57:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:57:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:57:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:57:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:57:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:57:25 INFO - (blobuploader) - INFO - Done attempting. 07:57:25 INFO - (blobuploader) - INFO - Iteration through files over. 07:57:25 INFO - Return code: 0 07:57:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:57:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:57:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ad16b3ef32757de6162e6c1e896008dd2e963697e8d651496daa883517d2ae6ff73ee2895cb393151e4af721b2b3e1ec224d2bd387f140fc5d3bec628e867041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90fde2404101d1379757480f6be648475c543cb1dda98df663e9c0f0cb2366ace0f9b13b5bae0bbf2a05fe9c2c2781a849a1ede38f9e9c452832bdfaf8406650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81035afde4ca29f24953f7edaa11e749289583d6b02f3d8372f152b4914034c82a67bd9e3f4560b31eb95396a900669f650279233f5100b38a1c40ce98ab2c59"} 07:57:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:57:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:57:25 INFO - Contents: 07:57:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ad16b3ef32757de6162e6c1e896008dd2e963697e8d651496daa883517d2ae6ff73ee2895cb393151e4af721b2b3e1ec224d2bd387f140fc5d3bec628e867041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90fde2404101d1379757480f6be648475c543cb1dda98df663e9c0f0cb2366ace0f9b13b5bae0bbf2a05fe9c2c2781a849a1ede38f9e9c452832bdfaf8406650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81035afde4ca29f24953f7edaa11e749289583d6b02f3d8372f152b4914034c82a67bd9e3f4560b31eb95396a900669f650279233f5100b38a1c40ce98ab2c59"} 07:57:25 INFO - Running post-run listener: copy_logs_to_upload_dir 07:57:25 INFO - Copying logs to upload dir... 07:57:25 INFO - mkdir: /builds/slave/test/build/upload/logs 07:57:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.406446 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-26 07:57:25.130101) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 07:57:25.133589) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962 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/ad16b3ef32757de6162e6c1e896008dd2e963697e8d651496daa883517d2ae6ff73ee2895cb393151e4af721b2b3e1ec224d2bd387f140fc5d3bec628e867041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90fde2404101d1379757480f6be648475c543cb1dda98df663e9c0f0cb2366ace0f9b13b5bae0bbf2a05fe9c2c2781a849a1ede38f9e9c452832bdfaf8406650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81035afde4ca29f24953f7edaa11e749289583d6b02f3d8372f152b4914034c82a67bd9e3f4560b31eb95396a900669f650279233f5100b38a1c40ce98ab2c59"} build_url:https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/PcmjbkjSRsmZSuaTzw1k5g/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/ad16b3ef32757de6162e6c1e896008dd2e963697e8d651496daa883517d2ae6ff73ee2895cb393151e4af721b2b3e1ec224d2bd387f140fc5d3bec628e867041", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/90fde2404101d1379757480f6be648475c543cb1dda98df663e9c0f0cb2366ace0f9b13b5bae0bbf2a05fe9c2c2781a849a1ede38f9e9c452832bdfaf8406650", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/81035afde4ca29f24953f7edaa11e749289583d6b02f3d8372f152b4914034c82a67bd9e3f4560b31eb95396a900669f650279233f5100b38a1c40ce98ab2c59"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 07:57:25.159344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:57:25.159720) ========= 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=20e666a3d4cfbb71505a330e000001b4-1472221864.894534-1845306962 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 07:57:25.245836) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 07:57:25.246255) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 07:57:25.246618) ========= ========= Total master_lag: 0.18 =========