builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1468903177.93 results: success (0) buildid: 20160718183003 builduid: cedfb4ab810141a3be462076187b24da revision: 9edae2531988223eb282ec0995eb736fd0fb8762 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:37.927747) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:37.928193) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:37.928482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:37.943031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:37.943357) ========= 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=836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-18 21:39:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-07-18 21:39:38 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086029 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:38.043126) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:38.043452) ========= 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=836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017446 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:38.093208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 21:39:38.093571) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9edae2531988223eb282ec0995eb736fd0fb8762 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9edae2531988223eb282ec0995eb736fd0fb8762 --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=836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-18 21:39:38,834 truncating revision to first 12 chars 2016-07-18 21:39:38,835 Setting DEBUG logging. 2016-07-18 21:39:38,835 attempt 1/10 2016-07-18 21:39:38,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9edae2531988?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 21:39:40,320 unpacking tar archive at: autoland-9edae2531988/testing/mozharness/ program finished with exit code 0 elapsedTime=1.834777 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 21:39:39.941862) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:39.942185) ========= script_repo_revision: 9edae2531988223eb282ec0995eb736fd0fb8762 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:39.942575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:39.942840) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 21:39:39.953466) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-18 21:39:39.953734) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:39:40 INFO - MultiFileLogger online at 20160718 21:39:40 in /builds/slave/test 21:39:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:39:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:39:40 INFO - {'append_to_log': False, 21:39:40 INFO - 'base_work_dir': '/builds/slave/test', 21:39:40 INFO - 'blob_upload_branch': 'Autoland', 21:39:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:39:40 INFO - 'branch': 'Autoland', 21:39:40 INFO - 'buildbot_json_path': 'buildprops.json', 21:39:40 INFO - 'config_files': ('talos/linux_config.py',), 21:39:40 INFO - 'default_actions': ('clobber', 21:39:40 INFO - 'read-buildbot-config', 21:39:40 INFO - 'download-and-extract', 21:39:40 INFO - 'populate-webroot', 21:39:40 INFO - 'create-virtualenv', 21:39:40 INFO - 'install', 21:39:40 INFO - 'run-tests'), 21:39:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:39:40 INFO - 'download_minidump_stackwalk': True, 21:39:40 INFO - 'download_symbols': 'ondemand', 21:39:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:39:40 INFO - 'tooltool.py': '/tools/tooltool.py', 21:39:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:39:40 INFO - '/tools/misc-python/virtualenv.py')}, 21:39:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:39:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:39:40 INFO - 'installer_path': 'installer.exe', 21:39:40 INFO - 'log_level': 'info', 21:39:40 INFO - 'log_name': 'talos', 21:39:40 INFO - 'log_to_console': True, 21:39:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:39:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:39:40 INFO - 'opt_config_files': (), 21:39:40 INFO - 'pip_index': False, 21:39:40 INFO - 'sps_profile': False, 21:39:40 INFO - 'sps_profile_interval': 0, 21:39:40 INFO - 'suite': 'dromaeojs', 21:39:40 INFO - 'system_bits': '32', 21:39:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:39:40 INFO - 'title': 'talos-linux64-ix-028', 21:39:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:39:40 INFO - 'use_talos_json': True, 21:39:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:39:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:39:40 INFO - 'work_dir': 'build'} 21:39:40 INFO - ##### 21:39:40 INFO - ##### Running clobber step. 21:39:40 INFO - ##### 21:39:40 INFO - Running pre-action listener: _resource_record_pre_action 21:39:40 INFO - Running main action method: clobber 21:39:40 INFO - rmtree: /builds/slave/test/build 21:39:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:39:41 INFO - Running post-action listener: _resource_record_post_action 21:39:41 INFO - ##### 21:39:41 INFO - ##### Finished clobber step (success) 21:39:41 INFO - ##### 21:39:41 INFO - ##### 21:39:41 INFO - ##### Running read-buildbot-config step. 21:39:41 INFO - ##### 21:39:41 INFO - Running pre-action listener: _resource_record_pre_action 21:39:41 INFO - Running main action method: read_buildbot_config 21:39:41 INFO - Using buildbot properties: 21:39:41 INFO - { 21:39:41 INFO - "project": "", 21:39:41 INFO - "product": "firefox", 21:39:41 INFO - "script_repo_revision": "production", 21:39:41 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:39:41 INFO - "repository": "", 21:39:41 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 21:39:41 INFO - "buildid": "20160718183003", 21:39:41 INFO - "pgo_build": "True", 21:39:41 INFO - "basedir": "/builds/slave/test", 21:39:41 INFO - "buildnumber": 32, 21:39:41 INFO - "slavename": "talos-linux64-ix-028", 21:39:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:39:41 INFO - "platform": "ubuntu64_hw", 21:39:41 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 21:39:41 INFO - "branch": "autoland", 21:39:41 INFO - "repo_path": "integration/autoland", 21:39:41 INFO - "revision": "9edae2531988223eb282ec0995eb736fd0fb8762", 21:39:41 INFO - "stage_platform": "linux64-pgo", 21:39:41 INFO - "builduid": "cedfb4ab810141a3be462076187b24da", 21:39:41 INFO - "slavebuilddir": "test" 21:39:41 INFO - } 21:39:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 21:39:41 INFO - Running post-action listener: _resource_record_post_action 21:39:41 INFO - ##### 21:39:41 INFO - ##### Finished read-buildbot-config step (success) 21:39:41 INFO - ##### 21:39:41 INFO - ##### 21:39:41 INFO - ##### Running download-and-extract step. 21:39:41 INFO - ##### 21:39:41 INFO - Running pre-action listener: _resource_record_pre_action 21:39:41 INFO - Running main action method: download_and_extract 21:39:41 INFO - mkdir: /builds/slave/test/build/tests 21:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:41 INFO - https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:39:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:39:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:39:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:39:41 INFO - Downloaded 1361 bytes. 21:39:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:39:41 INFO - Using the following test package requirements: 21:39:41 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:39:41 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:39:41 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:39:41 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'jsshell-linux-x86_64.zip'], 21:39:41 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:39:41 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:39:41 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:39:41 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:39:41 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:39:41 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:39:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:39:41 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:41 INFO - https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:39:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:39:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:39:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:39:43 INFO - Downloaded 24336217 bytes. 21:39:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:39:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:39:44 INFO - Return code: 0 21:39:44 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:39: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:44 INFO - https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:39:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:39:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:39:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:39:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:39:45 INFO - Downloaded 24336217 bytes. 21:39:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:39:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:39:46 INFO - Return code: 0 21:39:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:46 INFO - https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:39:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:39:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:39:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:39:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:39:47 INFO - Downloaded 14403317 bytes. 21:39:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:39:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:39:48 INFO - Return code: 0 21:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:48 INFO - https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:39:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:39:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:39:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:39:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:39:49 INFO - Downloaded 60866904 bytes. 21:39:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:39:49 INFO - mkdir: /builds/slave/test/properties 21:39:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:39:49 INFO - Writing to file /builds/slave/test/properties/build_url 21:39:49 INFO - Contents: 21:39:49 INFO - build_url:https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:39:50 INFO - Running post-action listener: _resource_record_post_action 21:39:50 INFO - Running post-action listener: set_extra_try_arguments 21:39:50 INFO - ##### 21:39:50 INFO - ##### Finished download-and-extract step (success) 21:39:50 INFO - ##### 21:39:50 INFO - ##### 21:39:50 INFO - ##### Running populate-webroot step. 21:39:50 INFO - ##### 21:39:50 INFO - Running pre-action listener: _resource_record_pre_action 21:39:50 INFO - Running main action method: populate_webroot 21:39:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:39:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:39:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:39:50 INFO - '--tppagecycles', 21:39:50 INFO - '7'], 21:39:50 INFO - 'tests': ['tsvgm']}}, 21:39:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:39:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:39:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:39:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:39:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:39:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:39:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['damp', 'tps']}, 21:39:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'tests': ['damp', 'tps']}, 21:39:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['dromaeo_dom']}, 21:39:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:39:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['basic_compositor_video']}, 21:39:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:39:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['a11yr', 21:39:50 INFO - 'ts_paint', 21:39:50 INFO - 'tpaint', 21:39:50 INFO - 'sessionrestore', 21:39:50 INFO - 'sessionrestore_no_auto_restore', 21:39:50 INFO - 'tabpaint']}, 21:39:50 INFO - 'other-e10s': {'tests': ['a11yr', 21:39:50 INFO - 'ts_paint', 21:39:50 INFO - 'tpaint', 21:39:50 INFO - 'sessionrestore', 21:39:50 INFO - 'sessionrestore_no_auto_restore', 21:39:50 INFO - 'tabpaint']}, 21:39:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:39:50 INFO - 'ts_paint', 21:39:50 INFO - 'tpaint', 21:39:50 INFO - 'sessionrestore', 21:39:50 INFO - 'sessionrestore_no_auto_restore', 21:39:50 INFO - 'tabpaint']}, 21:39:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:39:50 INFO - 'ts_paint', 21:39:50 INFO - 'tpaint', 21:39:50 INFO - 'sessionrestore', 21:39:50 INFO - 'sessionrestore_no_auto_restore', 21:39:50 INFO - 'tabpaint']}, 21:39:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['a11yr', 21:39:50 INFO - 'ts_paint', 21:39:50 INFO - 'tpaint', 21:39:50 INFO - 'sessionrestore', 21:39:50 INFO - 'sessionrestore_no_auto_restore', 21:39:50 INFO - 'tabpaint']}, 21:39:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['a11yr', 21:39:50 INFO - 'ts_paint', 21:39:50 INFO - 'tpaint', 21:39:50 INFO - 'sessionrestore', 21:39:50 INFO - 'sessionrestore_no_auto_restore', 21:39:50 INFO - 'tabpaint']}, 21:39:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['tsvgx', 21:39:50 INFO - 'tsvgr_opacity', 21:39:50 INFO - 'tart', 21:39:50 INFO - 'tscrollx', 21:39:50 INFO - 'cart']}, 21:39:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:39:50 INFO - 'tsvgr_opacity', 21:39:50 INFO - 'tart', 21:39:50 INFO - 'tscrollx', 21:39:50 INFO - 'cart']}, 21:39:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'talos_options': ['--disable-e10s'], 21:39:50 INFO - 'tests': ['tp5o']}, 21:39:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'tests': ['tp5o']}, 21:39:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'talos_options': ['--disable-e10s', 21:39:50 INFO - '--xperf_path', 21:39:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:39:50 INFO - 'tests': ['tp5n']}, 21:39:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:39:50 INFO - 'talos_options': ['--xperf_path', 21:39:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:39:50 INFO - 'tests': ['tp5n']}}, 21:39:50 INFO - 'talos.zip': {'path': '', 21:39:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:39:50 INFO - Running post-action listener: _resource_record_post_action 21:39:50 INFO - ##### 21:39:50 INFO - ##### Finished populate-webroot step (success) 21:39:50 INFO - ##### 21:39:50 INFO - ##### 21:39:50 INFO - ##### Running create-virtualenv step. 21:39:50 INFO - ##### 21:39:50 INFO - Running pre-action listener: _resource_record_pre_action 21:39:50 INFO - Running main action method: create_virtualenv 21:39:50 INFO - Creating virtualenv /builds/slave/test/build/venv 21:39:50 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 21:39:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:39:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:39:50 INFO - Using real prefix '/usr' 21:39:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:39:50 INFO - Installing distribute.............................................................................................................................................................................................done. 21:39:52 INFO - Installing pip.................done. 21:39:52 INFO - Return code: 0 21:39:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:39:52 INFO - Using env: {'DISPLAY': ':0', 21:39:52 INFO - 'HOME': '/home/cltbld', 21:39:52 INFO - 'LANG': 'en_US.UTF-8', 21:39:52 INFO - 'LANGUAGE': 'en_US:en', 21:39:52 INFO - 'LOGNAME': 'cltbld', 21:39:52 INFO - 'MAIL': '/var/mail/cltbld', 21:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:52 INFO - 'MOZ_NO_REMOTE': '1', 21:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:52 INFO - 'NO_EM_RESTART': '1', 21:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:52 INFO - 'PWD': '/builds/slave/test', 21:39:52 INFO - 'SHELL': '/bin/bash', 21:39:52 INFO - 'SHLVL': '1', 21:39:52 INFO - 'TERM': 'linux', 21:39:52 INFO - 'TMOUT': '86400', 21:39:52 INFO - 'USER': 'cltbld', 21:39:52 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:52 INFO - '_': '/tools/buildbot/bin/python'} 21:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:52 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 21:39:52 INFO - Cleaning up... 21:39:52 INFO - Return code: 0 21:39:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:39:52 INFO - Using env: {'DISPLAY': ':0', 21:39:52 INFO - 'HOME': '/home/cltbld', 21:39:52 INFO - 'LANG': 'en_US.UTF-8', 21:39:52 INFO - 'LANGUAGE': 'en_US:en', 21:39:52 INFO - 'LOGNAME': 'cltbld', 21:39:52 INFO - 'MAIL': '/var/mail/cltbld', 21:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:52 INFO - 'MOZ_NO_REMOTE': '1', 21:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:52 INFO - 'NO_EM_RESTART': '1', 21:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:52 INFO - 'PWD': '/builds/slave/test', 21:39:52 INFO - 'SHELL': '/bin/bash', 21:39:52 INFO - 'SHLVL': '1', 21:39:52 INFO - 'TERM': 'linux', 21:39:52 INFO - 'TMOUT': '86400', 21:39:52 INFO - 'USER': 'cltbld', 21:39:52 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:52 INFO - '_': '/tools/buildbot/bin/python'} 21:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:53 INFO - Downloading/unpacking psutil>=3.1.1 21:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:39:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:39:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:39:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:39:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:39:55 INFO - Installing collected packages: psutil 21:39:55 INFO - Running setup.py install for psutil 21:39:55 INFO - building 'psutil._psutil_linux' extension 21:39:55 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 21:39:55 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 21:39:55 INFO - building 'psutil._psutil_posix' extension 21:39:55 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 21:39:55 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 21:39:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:39:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:39:55 INFO - Successfully installed psutil 21:39:55 INFO - Cleaning up... 21:39:55 INFO - Return code: 0 21:39:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:55 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:39:55 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 21:39:55 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 21:39:55 INFO - Using env: {'DISPLAY': ':0', 21:39:55 INFO - 'HOME': '/home/cltbld', 21:39:55 INFO - 'LANG': 'en_US.UTF-8', 21:39:55 INFO - 'LANGUAGE': 'en_US:en', 21:39:55 INFO - 'LOGNAME': 'cltbld', 21:39:55 INFO - 'MAIL': '/var/mail/cltbld', 21:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:55 INFO - 'MOZ_NO_REMOTE': '1', 21:39:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:55 INFO - 'NO_EM_RESTART': '1', 21:39:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:55 INFO - 'PWD': '/builds/slave/test', 21:39:55 INFO - 'SHELL': '/bin/bash', 21:39:55 INFO - 'SHLVL': '1', 21:39:55 INFO - 'TERM': 'linux', 21:39:55 INFO - 'TMOUT': '86400', 21:39:55 INFO - 'USER': 'cltbld', 21:39:55 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:55 INFO - '_': '/tools/buildbot/bin/python'} 21:39:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:39:55 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 21:39:55 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 21:39:55 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 21:39:55 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 21:39:55 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 21:39:55 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 21:39:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:39:57 INFO - Installing collected packages: mozsystemmonitor 21:39:57 INFO - Running setup.py install for mozsystemmonitor 21:39:58 INFO - Successfully installed mozsystemmonitor 21:39:58 INFO - Cleaning up... 21:39:58 INFO - Return code: 0 21:39:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:39:58 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:39:58 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 21:39:58 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 21:39:58 INFO - Using env: {'DISPLAY': ':0', 21:39:58 INFO - 'HOME': '/home/cltbld', 21:39:58 INFO - 'LANG': 'en_US.UTF-8', 21:39:58 INFO - 'LANGUAGE': 'en_US:en', 21:39:58 INFO - 'LOGNAME': 'cltbld', 21:39:58 INFO - 'MAIL': '/var/mail/cltbld', 21:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:39:58 INFO - 'MOZ_NO_REMOTE': '1', 21:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:39:58 INFO - 'NO_EM_RESTART': '1', 21:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:39:58 INFO - 'PWD': '/builds/slave/test', 21:39:58 INFO - 'SHELL': '/bin/bash', 21:39:58 INFO - 'SHLVL': '1', 21:39:58 INFO - 'TERM': 'linux', 21:39:58 INFO - 'TMOUT': '86400', 21:39:58 INFO - 'USER': 'cltbld', 21:39:58 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:39:58 INFO - '_': '/tools/buildbot/bin/python'} 21:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:39:58 INFO - Downloading/unpacking jsonschema==2.5.1 21:39:58 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 21:39:58 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 21:39:58 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 21:39:58 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 21:39:58 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 21:39:58 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 21:40:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:40:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:40: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 21:40: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 21:40: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 21:40: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 21:40: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 21:40: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 21:40:00 INFO - Downloading functools32-3.2.3-2.tar.gz 21:40:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:40:00 INFO - warning: no files found matching '*.txt' 21:40:00 INFO - no previously-included directories found matching 'build' 21:40:00 INFO - no previously-included directories found matching 'dist' 21:40:00 INFO - no previously-included directories found matching '.git*' 21:40:00 INFO - Installing collected packages: jsonschema, functools32 21:40:00 INFO - Running setup.py install for functools32 21:40:00 INFO - warning: no files found matching '*.txt' 21:40:00 INFO - no previously-included directories found matching 'build' 21:40:00 INFO - no previously-included directories found matching 'dist' 21:40:00 INFO - no previously-included directories found matching '.git*' 21:40:00 INFO - Successfully installed jsonschema functools32 21:40:00 INFO - Cleaning up... 21:40:01 INFO - Return code: 0 21:40:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:01 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:40:01 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 21:40:01 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 21:40:01 INFO - Using env: {'DISPLAY': ':0', 21:40:01 INFO - 'HOME': '/home/cltbld', 21:40:01 INFO - 'LANG': 'en_US.UTF-8', 21:40:01 INFO - 'LANGUAGE': 'en_US:en', 21:40:01 INFO - 'LOGNAME': 'cltbld', 21:40:01 INFO - 'MAIL': '/var/mail/cltbld', 21:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:01 INFO - 'MOZ_NO_REMOTE': '1', 21:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:01 INFO - 'NO_EM_RESTART': '1', 21:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:01 INFO - 'PWD': '/builds/slave/test', 21:40:01 INFO - 'SHELL': '/bin/bash', 21:40:01 INFO - 'SHLVL': '1', 21:40:01 INFO - 'TERM': 'linux', 21:40:01 INFO - 'TMOUT': '86400', 21:40:01 INFO - 'USER': 'cltbld', 21:40:01 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:01 INFO - '_': '/tools/buildbot/bin/python'} 21:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:01 INFO - Downloading/unpacking blobuploader==1.2.4 21:40:01 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 21:40:01 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 21:40:01 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 21:40:01 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 21:40:01 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 21:40:01 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 21:40:03 INFO - Downloading blobuploader-1.2.4.tar.gz 21:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:40:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:40:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 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 21:40:03 INFO - Downloading docopt-0.6.1.tar.gz 21:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:40:03 INFO - Installing collected packages: blobuploader, requests, docopt 21:40:03 INFO - Running setup.py install for blobuploader 21:40:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:40:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:40:04 INFO - Running setup.py install for requests 21:40:04 INFO - Running setup.py install for docopt 21:40:04 INFO - Successfully installed blobuploader requests docopt 21:40:04 INFO - Cleaning up... 21:40:04 INFO - Return code: 0 21:40:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:04 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:40:04 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 21:40:04 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 21:40:04 INFO - Using env: {'DISPLAY': ':0', 21:40:04 INFO - 'HOME': '/home/cltbld', 21:40:04 INFO - 'LANG': 'en_US.UTF-8', 21:40:04 INFO - 'LANGUAGE': 'en_US:en', 21:40:04 INFO - 'LOGNAME': 'cltbld', 21:40:04 INFO - 'MAIL': '/var/mail/cltbld', 21:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:04 INFO - 'MOZ_NO_REMOTE': '1', 21:40:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:04 INFO - 'NO_EM_RESTART': '1', 21:40:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:04 INFO - 'PWD': '/builds/slave/test', 21:40:04 INFO - 'SHELL': '/bin/bash', 21:40:04 INFO - 'SHLVL': '1', 21:40:04 INFO - 'TERM': 'linux', 21:40:04 INFO - 'TMOUT': '86400', 21:40:04 INFO - 'USER': 'cltbld', 21:40:04 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:04 INFO - '_': '/tools/buildbot/bin/python'} 21:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:40:04 INFO - Running setup.py (path:/tmp/pip-aaDEP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:40:04 INFO - Running setup.py (path:/tmp/pip-mU1Mow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:40:04 INFO - Running setup.py (path:/tmp/pip-lc6vob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:40:04 INFO - Running setup.py (path:/tmp/pip-mH9kNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:40:05 INFO - Running setup.py (path:/tmp/pip-EzEMi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:40:05 INFO - Running setup.py (path:/tmp/pip-AHBGxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:40:05 INFO - Running setup.py (path:/tmp/pip-t8Ud5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:40:05 INFO - Running setup.py (path:/tmp/pip-vxKODc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:40:05 INFO - Running setup.py (path:/tmp/pip-AwOF6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:40:05 INFO - Running setup.py (path:/tmp/pip-FiFvQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:40:05 INFO - Running setup.py (path:/tmp/pip-Ks0yQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:40:05 INFO - Running setup.py (path:/tmp/pip-1B0ayb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:40:05 INFO - Running setup.py (path:/tmp/pip-tJS4nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:40:05 INFO - Running setup.py (path:/tmp/pip-rIZouD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:05 INFO - Running setup.py (path:/tmp/pip-RTkGhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:40:05 INFO - Running setup.py (path:/tmp/pip-B4fwSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:40:05 INFO - Running setup.py (path:/tmp/pip-tKEQrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:40:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:40:05 INFO - Running setup.py install for manifestparser 21:40:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Running setup.py install for mozcrash 21:40:06 INFO - Running setup.py install for mozdebug 21:40:06 INFO - Running setup.py install for mozdevice 21:40:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Running setup.py install for mozfile 21:40:06 INFO - Running setup.py install for mozhttpd 21:40:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Running setup.py install for mozinfo 21:40:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Running setup.py install for mozInstall 21:40:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:40:06 INFO - Running setup.py install for mozleak 21:40:06 INFO - Running setup.py install for mozlog 21:40:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Running setup.py install for moznetwork 21:40:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Running setup.py install for mozprocess 21:40:07 INFO - Running setup.py install for mozprofile 21:40:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Running setup.py install for mozrunner 21:40:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Running setup.py install for mozscreenshot 21:40:07 INFO - Running setup.py install for moztest 21:40:07 INFO - Running setup.py install for mozversion 21:40:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:40:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:40:07 INFO - Cleaning up... 21:40:07 INFO - Return code: 0 21:40:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:07 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:40:07 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 21:40:07 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 21:40:07 INFO - Using env: {'DISPLAY': ':0', 21:40:07 INFO - 'HOME': '/home/cltbld', 21:40:07 INFO - 'LANG': 'en_US.UTF-8', 21:40:07 INFO - 'LANGUAGE': 'en_US:en', 21:40:07 INFO - 'LOGNAME': 'cltbld', 21:40:07 INFO - 'MAIL': '/var/mail/cltbld', 21:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:07 INFO - 'MOZ_NO_REMOTE': '1', 21:40:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:07 INFO - 'NO_EM_RESTART': '1', 21:40:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:07 INFO - 'PWD': '/builds/slave/test', 21:40:07 INFO - 'SHELL': '/bin/bash', 21:40:07 INFO - 'SHLVL': '1', 21:40:07 INFO - 'TERM': 'linux', 21:40:07 INFO - 'TMOUT': '86400', 21:40:07 INFO - 'USER': 'cltbld', 21:40:07 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:07 INFO - '_': '/tools/buildbot/bin/python'} 21:40:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:40:07 INFO - Running setup.py (path:/tmp/pip-d4PEEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:40:08 INFO - Running setup.py (path:/tmp/pip-zxkCs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:40:08 INFO - Running setup.py (path:/tmp/pip-HbSP8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:40:08 INFO - Running setup.py (path:/tmp/pip-ZzVxrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:40:08 INFO - Running setup.py (path:/tmp/pip-ZEwd3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:40:08 INFO - Running setup.py (path:/tmp/pip-rkJ7_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:40:08 INFO - Running setup.py (path:/tmp/pip-UXeX_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:40:08 INFO - Running setup.py (path:/tmp/pip-qH2h1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:40:08 INFO - Running setup.py (path:/tmp/pip-ztEzh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:40:08 INFO - Running setup.py (path:/tmp/pip-hTPiM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:40:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:40:08 INFO - Running setup.py (path:/tmp/pip-2LQgHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:40:08 INFO - Running setup.py (path:/tmp/pip-U80mx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:40:08 INFO - Running setup.py (path:/tmp/pip-ePIZhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:40:08 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)) 21:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:40:08 INFO - Running setup.py (path:/tmp/pip-l08ax2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:40:09 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)) 21:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:09 INFO - Running setup.py (path:/tmp/pip-VZssBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:40:09 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)) 21:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:40:09 INFO - Running setup.py (path:/tmp/pip-6EgzoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:40:09 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)) 21:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:40:09 INFO - Running setup.py (path:/tmp/pip-kwSrqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:40:09 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)) 21:40:09 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)) 21:40:09 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)) 21:40:09 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)) 21:40:09 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)) 21:40:09 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)) 21:40:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:40:09 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 21:40:09 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 21:40:09 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 21:40:09 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 21:40:09 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 21:40:09 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 21:40:11 INFO - Downloading blessings-1.6.tar.gz 21:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:40:11 INFO - Installing collected packages: blessings 21:40:11 INFO - Running setup.py install for blessings 21:40:11 INFO - Successfully installed blessings 21:40:11 INFO - Cleaning up... 21:40:11 INFO - Return code: 0 21:40:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:40:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:40:11 INFO - Reading from file tmpfile_stdout 21:40:11 INFO - Current package versions: 21:40:11 INFO - argparse == 1.2.1 21:40:11 INFO - blessings == 1.6 21:40:11 INFO - blobuploader == 1.2.4 21:40:11 INFO - docopt == 0.6.1 21:40:11 INFO - functools32 == 3.2.3-2 21:40:11 INFO - jsonschema == 2.5.1 21:40:11 INFO - manifestparser == 1.1 21:40:11 INFO - mozInstall == 1.12 21:40:11 INFO - mozcrash == 0.17 21:40:11 INFO - mozdebug == 0.1 21:40:11 INFO - mozdevice == 0.48 21:40:11 INFO - mozfile == 1.2 21:40:11 INFO - mozhttpd == 0.7 21:40:11 INFO - mozinfo == 0.9 21:40:11 INFO - mozleak == 0.1 21:40:11 INFO - mozlog == 3.2 21:40:11 INFO - moznetwork == 0.27 21:40:11 INFO - mozprocess == 0.23 21:40:11 INFO - mozprofile == 0.28 21:40:11 INFO - mozrunner == 6.12 21:40:11 INFO - mozscreenshot == 0.1 21:40:11 INFO - mozsystemmonitor == 0.3 21:40:11 INFO - moztest == 0.7 21:40:11 INFO - mozversion == 1.4 21:40:11 INFO - psutil == 3.1.1 21:40:11 INFO - requests == 1.2.3 21:40:11 INFO - wsgiref == 0.1.2 21:40:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:40: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:11 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:40:11 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 21:40:11 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 21:40:11 INFO - Using env: {'DISPLAY': ':0', 21:40:11 INFO - 'HOME': '/home/cltbld', 21:40:11 INFO - 'LANG': 'en_US.UTF-8', 21:40:11 INFO - 'LANGUAGE': 'en_US:en', 21:40:11 INFO - 'LOGNAME': 'cltbld', 21:40:11 INFO - 'MAIL': '/var/mail/cltbld', 21:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:11 INFO - 'MOZ_NO_REMOTE': '1', 21:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:11 INFO - 'NO_EM_RESTART': '1', 21:40:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:11 INFO - 'PWD': '/builds/slave/test', 21:40:11 INFO - 'SHELL': '/bin/bash', 21:40:11 INFO - 'SHLVL': '1', 21:40:11 INFO - 'TERM': 'linux', 21:40:11 INFO - 'TMOUT': '86400', 21:40:11 INFO - 'USER': 'cltbld', 21:40:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:11 INFO - '_': '/tools/buildbot/bin/python'} 21:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 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)) 21:40:11 INFO - Cleaning up... 21:40:11 INFO - Return code: 0 21:40:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:40: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:40:11 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 0x1d9c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x205d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, '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': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', '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 21:40:11 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 21:40:11 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 21:40:11 INFO - Using env: {'DISPLAY': ':0', 21:40:11 INFO - 'HOME': '/home/cltbld', 21:40:11 INFO - 'LANG': 'en_US.UTF-8', 21:40:11 INFO - 'LANGUAGE': 'en_US:en', 21:40:11 INFO - 'LOGNAME': 'cltbld', 21:40:11 INFO - 'MAIL': '/var/mail/cltbld', 21:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:11 INFO - 'MOZ_NO_REMOTE': '1', 21:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:11 INFO - 'NO_EM_RESTART': '1', 21:40:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:11 INFO - 'PWD': '/builds/slave/test', 21:40:11 INFO - 'SHELL': '/bin/bash', 21:40:11 INFO - 'SHLVL': '1', 21:40:11 INFO - 'TERM': 'linux', 21:40:11 INFO - 'TMOUT': '86400', 21:40:11 INFO - 'USER': 'cltbld', 21:40:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:11 INFO - '_': '/tools/buildbot/bin/python'} 21:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:40:12 INFO - Cleaning up... 21:40:12 INFO - Return code: 0 21:40:12 INFO - Running post-action listener: _resource_record_post_action 21:40:12 INFO - Running post-action listener: _start_resource_monitoring 21:40:12 INFO - Starting resource monitoring. 21:40:12 INFO - ##### 21:40:12 INFO - ##### Finished create-virtualenv step (success) 21:40:12 INFO - ##### 21:40:12 INFO - ##### 21:40:12 INFO - ##### Running install step. 21:40:12 INFO - ##### 21:40:12 INFO - Running pre-action listener: _resource_record_pre_action 21:40:12 INFO - Running main action method: install 21:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:40:12 INFO - Reading from file tmpfile_stdout 21:40:12 INFO - Detecting whether we're running mozinstall >=1.0... 21:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:40:12 INFO - Reading from file tmpfile_stdout 21:40:12 INFO - Output received: 21:40:12 INFO - Usage: mozinstall [options] installer 21:40:12 INFO - Options: 21:40:12 INFO - -h, --help show this help message and exit 21:40:12 INFO - -d DEST, --destination=DEST 21:40:12 INFO - Directory to install application into. [default: 21:40:12 INFO - "/builds/slave/test"] 21:40:12 INFO - --app=APP Application being installed. [default: firefox] 21:40:12 INFO - mkdir: /builds/slave/test/build/application 21:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:40:29 INFO - Reading from file tmpfile_stdout 21:40:29 INFO - Output received: 21:40:29 INFO - /builds/slave/test/build/application/firefox/firefox 21:40:29 INFO - Running post-action listener: _resource_record_post_action 21:40:29 INFO - ##### 21:40:29 INFO - ##### Finished install step (success) 21:40:29 INFO - ##### 21:40:29 INFO - ##### 21:40:29 INFO - ##### Running run-tests step. 21:40:29 INFO - ##### 21:40:29 INFO - Running pre-action listener: _resource_record_pre_action 21:40:29 INFO - Running main action method: run_tests 21:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:40:29 INFO - Python 2.7.3 21:40:29 INFO - Return code: 0 21:40:29 INFO - grabbing minidump binary from tooltool 21:40:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:29 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 0x205b3b0>, '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 0x205c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x205c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:40:29 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 21:40:29 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 21:40:29 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 21:40:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:40:29 INFO - Return code: 0 21:40:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:40:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:40:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:40:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:40:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:40:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 21:40:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 21:40:29 INFO - Using env: {'DISPLAY': ':0', 21:40:29 INFO - 'HOME': '/home/cltbld', 21:40:29 INFO - 'LANG': 'en_US.UTF-8', 21:40:29 INFO - 'LANGUAGE': 'en_US:en', 21:40:29 INFO - 'LOGNAME': 'cltbld', 21:40:29 INFO - 'MAIL': '/var/mail/cltbld', 21:40:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:40:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:40:29 INFO - 'MOZ_NO_REMOTE': '1', 21:40:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:40:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:40:29 INFO - 'NO_EM_RESTART': '1', 21:40:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:40:29 INFO - 'PWD': '/builds/slave/test', 21:40:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:40:29 INFO - 'SHELL': '/bin/bash', 21:40:29 INFO - 'SHLVL': '1', 21:40:29 INFO - 'TERM': 'linux', 21:40:29 INFO - 'TMOUT': '86400', 21:40:29 INFO - 'USER': 'cltbld', 21:40:29 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665', 21:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:40:29 INFO - '_': '/tools/buildbot/bin/python'} 21:40:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 21:40:29 INFO - mozversion application_buildid: 20160718183003 21:40:29 INFO - mozversion application_changeset: 9edae2531988223eb282ec0995eb736fd0fb8762 21:40:29 INFO - mozversion application_display_name: Nightly 21:40:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:40:29 INFO - mozversion application_name: Firefox 21:40:29 INFO - mozversion application_remotingname: firefox 21:40:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:40:29 INFO - mozversion application_vendor: Mozilla 21:40:29 INFO - mozversion application_version: 50.0a1 21:40:29 INFO - mozversion platform_buildid: 20160718183003 21:40:29 INFO - mozversion platform_changeset: 9edae2531988223eb282ec0995eb736fd0fb8762 21:40:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:40:29 INFO - mozversion platform_version: 50.0a1 21:40:29 INFO - using testdate: 1468903229 21:40:29 INFO - actual date: 1468903229 21:40:29 INFO - starting webserver on 'localhost:47852' 21:40:29 INFO - SUITE-START | Running 2 tests 21:40:29 INFO - TEST-START | dromaeo_css 21:40:29 INFO - operating with platform_type : linux_ 21:40:29 INFO - Initialising browser for dromaeo_css test... 21:40:29 INFO - TEST-INFO | started process 28308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxhJwvt/profile http://localhost:47852/getInfo.html) 21:40:33 INFO - PROCESS | 28308 | __metrics Screen width/height:1600/1200 21:40:33 INFO - PROCESS | 28308 | colorDepth:24 21:40:33 INFO - PROCESS | 28308 | Browser inner width/height: 1024/697 21:40:33 INFO - PROCESS | 28308 | __metrics 21:40:34 INFO - PROCESS | 28308 | 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" 21:40:34 INFO - PROCESS | 28308 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:40:35 INFO - PROCESS | 28308 | console.error: PushService: 21:40:35 INFO - PROCESS | 28308 | stateChangeProcessEnqueue: Error transitioning state 21:40:35 INFO - PROCESS | 28308 | UnknownError 21:40:35 INFO - TEST-INFO | 28308: exit 0 21:40:35 INFO - Browser initialized. 21:40:35 INFO - Running cycle 1/1 for dromaeo_css test... 21:40:35 INFO - TEST-INFO | started process 28389 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxhJwvt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:40:47 INFO - PROCESS | 28389 | RSS: Main: 158052352 21:40:47 INFO - PROCESS | 28389 | 21:41:39 INFO - PROCESS | 28389 | 1468903299101 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] 21:43:46 INFO - PROCESS | 28389 | Cycle 1(1): loaded http://localhost:47852/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47852/tests/dromaeo/cssquery-ext.html) 21:43:47 INFO - PROCESS | 28389 | RSS: Main: 255651840 21:43:47 INFO - PROCESS | 28389 | 21:46:49 INFO - PROCESS | 28389 | Cycle 1(1): loaded http://localhost:47852/tests/dromaeo/cssquery-ext.html (next: http://localhost:47852/tests/dromaeo/cssquery-jquery.html) 21:46:50 INFO - PROCESS | 28389 | RSS: Main: 242307072 21:46:50 INFO - PROCESS | 28389 | 21:49:52 INFO - PROCESS | 28389 | Cycle 1(1): loaded http://localhost:47852/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47852/tests/dromaeo/cssquery-mootools.html) 21:49:53 INFO - PROCESS | 28389 | RSS: Main: 249188352 21:49:53 INFO - PROCESS | 28389 | 21:52:55 INFO - PROCESS | 28389 | Cycle 1(1): loaded http://localhost:47852/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47852/tests/dromaeo/cssquery-prototype.html) 21:52:55 INFO - PROCESS | 28389 | RSS: Main: 236785664 21:52:55 INFO - PROCESS | 28389 | 21:55:58 INFO - PROCESS | 28389 | Cycle 1(1): loaded http://localhost:47852/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47852/tests/dromaeo/cssquery-yui.html) 21:55:59 INFO - PROCESS | 28389 | RSS: Main: 208965632 21:55:59 INFO - PROCESS | 28389 | 21:59:01 INFO - PROCESS | 28389 | Cycle 1(1): loaded http://localhost:47852/tests/dromaeo/cssquery-yui.html (next: http://localhost:47852/tests/dromaeo/cssquery-dojo.html) 21:59:02 INFO - PROCESS | 28389 | RSS: Main: 275832832 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | __start_tp_report 21:59:02 INFO - PROCESS | 28389 | _x_x_mozilla_page_load 21:59:02 INFO - PROCESS | 28389 | _x_x_mozilla_page_load_details 21:59:02 INFO - PROCESS | 28389 | |i|pagename|runs| 21:59:02 INFO - PROCESS | 28389 | |0;dojo.html;3026.379592183602;3053.5572342010396;3079.230192451887;3080.8151510909347;3093.613298337708;5256.579473642109;5281.339832520935;5298.178782288742;5401.405845357012;5425.701586412751;5270.578353731702;5292.444293349201;5299.125644268701;5317;5333.38666053404;4766.189747742892;4851.199552073921;4865.975670121645;4894.804207831682;5065.594752419797;4920.3111564381015;4930.03864246472;4946.777395017215;4994.575461085812;5016.122178618745;4783.402074740658;4805.447373552034;4816.759162041919;4845.927311090337;4850.3937007873665;4809.254565542346;4810.350602668665;4823.059503396839;4833.806647734086;4887.3402090717655;4815;4839.951600484021;4840.878978025542;4850.733209673466;4890.997345544155;5178.844634660966;5225.6603320784025;5289.3123893893935;5293.523582877559;5335.653182543122;5727.1695604137585;5729.369769325371;5731.111677689923;5758.395368486333;5765.077587585966;5723.771049158028;5773.134029895507;5792.478676919055;5802.506786923074;5807.506361959238;5229.790808367661;5275.393329767049;5318.494742999409;5320.14877619579;5401.378841433286;5903.645781253139;5903.911441328383;5912.733926973297;5915.053591425351;5920.644761314335;5506.229127922094;5666;5679.659220446786;5681.204631351614;5683.54531637468;5583.106702927512;5647.463490968352;5652.689102099342;5748.9425105748405;5770.10563362671;5671.489565939085;5677.460641239076;5679.829605111853;5680.602357834912;5694.202811606378;5560.054790685513;5621.072523033736;5677.233573467612;5688.829335119953;5691.686957217393;4292.463442069741;4337.566243375701;4372.387865698741;4410.07388448423;4411.669124815588;3892.4355968384425;3893.0851249956236;3903.316919539126;3908.355121404993;3932.8820135395986;5230.503102205361;5242.633015688865;5324.547413469821;5386.596005299618;5423.078076726966;5701.914471282934;5728.971355143281;5744.511716504144;5747.08046712524;5763.625364351304;5332.893342133213;5336.092864213016;5340.572754179656;5341.759620817073;5347.3048503695045;4675.251959686502;4766.285057241442;4791.401074865642;4821.445533763592;4822.662413631012;5923.733431995571;5930.584859059911;5933.940660593339;5934.910976335358;5935.376785437553;5720.456556627114;5730.255066841284;5730.9713451434145;5739.598228123991;5741.1388291756575;5723.399043100415;5818.592698511064;5819.039858423482;5842.766289348378;5906.114082887601;5621.718914054362;5633.774649013993;5633.943660563342;5634.380218176079;5634.577406694432;5568.6658800472105;5666.433356664301;5702.059160238514;5720.2849643794525;5722.484976352148;5520.86197845041;5544.3346798384455;5576.832695019156;5590.077637189981;5592.468715472024;5585.329760428612;5585.3297604287745;5590.86022849432;5597.692126932895;5598.86002849932;5493.752781124939;5505.256790333414;5542.201188399832;5589.189567512606;5603.551715862803;5594.776208951596;5671.631343962629;5704.372519022987;5735.025045742317;5740.655560666373;5543.9168412474655;5548.500634942875;5555.527780138734;5557.472040156179;5559.916601250903 21:59:02 INFO - PROCESS | 28389 | |1;ext.html;569.3424095170077;609.8048624440175;610.7648555306206;612.9172561704171;634.847636567224;33088.33823323532;37165.25669486612;43780.78109609451;44521.55478445214;44697.553024469744;46766;46949.29576056362;47042;47190.76404617972;47253.76373118138;9092.863607045883;9224.769380765467;9242.907570924272;9250.121238482334;9256.6297348106;9061;9138.817223655524;9140.314476414262;9146.817063658722;9150.954245228797;9238.6304547818;9274.582643781012;9279.350445468819;9283;9285.675001374953;9181.908180918172;9202;9233.215176709988;9233.815323693556;9242.260619150486;9033.819323613556;9115.680951166678;9118.179363857285;9119.954400228024;9120.45277283365;44642.776786115865;44722.77638611786;44926.10147797059;44926.550734492565;44939.775301123285;3511.929761404783;3560.430331147004;3979.243943650697;4042.939355909664;4185.769782661952;31639.36721265562;33729.49405758915;34303;34391;34553;4008.6392224699916;4125.071858831769;4153.522344930341;4164.437800896901;4180;396067;396478;398087;398168;398937;32624.673753262163;63214.83925803691;63512.682436588446;63688.36311636917;63829.36170638327;34204.82897585546;35778.821105894305;36010.27979440449;36037.459438108446;36083.4587481188;15942.760858587131;16119.435819746253;16146.354145834035;16157.757633635505;16162.272697728631;13457.596272111852;13487.797683034762;13511.864881351257;13519.459221631221;13531.05282630226;8443.197896199852;8458.196471335214;8476.745697629081;8510.106438823957;8553.315734741205;3258.1365938026443;3300.174956260935;3301.059198128522;3302.1249368917256;3325.285063711314;1012.9341592796592;1016.6238491758097;1037.9257468520045;1070.6466865934224;1102.8070087734775;33525;34028.48957265643;34130.48804267938;34149.48775768365;34493.65506344954;1109.9919218916825;1122.1247427006947;1133.9092872570175;1195.5934982106126;1229.8032314829584;17451.214695338742;17488.825111748974;17522.649547008987;17907.1941762621;17977.640447190985;648079;652703;654089;655251;655921;61396.693016534635;62724.372756272765;62955;63188.36811631825;63329.36670633327;1254.5797157952006;1299.1295831792722;1362.264377236301;1380.951666691681;1389.8471168171493;31131.53302700416;34730.652693472744;34938.82530587331;35063.82468087745;35126.47310290399;53242;53375.199372008676;53936;54096;54104;409284;411589;411909;412553;413837;25338;28323.57514637241;28533.42933141284;28578.714212857605;29615.111546653636;50846;50889.49110508848;51177.488225117275;51689.48310516847;52550.47449525456;43402.56597433985;44142.33786493269;45060.32409513794;45351;45896.54103459056;43858.34212486879;43910.12179756451;44556.77721611371;45042.099158016;45311.773441133904;42657.36013959855;42938.78530607327;44198.77900610476;44281;44569.33146002878;37877;38477.80761096177;38589;38630.22739545137;39406.2118757629;28822.279443014093;28900.915532360996;28970.565441518815;29020.709792902646;29552.852235739545 21:59:02 INFO - PROCESS | 28389 | |2;jquery.html;387.4459522882279;388.59197842265615;389.23321057516694;403.3344980781712;404.2238901309486;18558.257669693212;19894.801051989474;20586.48533786656;20595.897020514876;20599.176032958683;12221.816672749894;12508.06189535784;12709.936450317758;12990.2205867648;13093.607191784238;5317.7607007684555;5448.645838020516;5506.366767821692;5521.1442226454765;5523.806666766664;3991.800409979504;5731.369549349568;5966.254218222723;5969;6077.817665470043;5766.192733017381;6062.302835173968;6062.514998800108;6068.817935461943;6083.513318934474;6026.517878569726;6096.72564734588;6118.235220597426;6126.47924926384;6131.570790044699;3886.805659717003;5299;5556.444355564452;5561.944380556183;5562.554995600382;14586;14588;19401.708974365396;24432.755672443225;24467.87766061176;2407.3500154958238;2418.093215044358;2459.557279689655;2479;2795.8741856616502;9768.21854251658;9931.404115753006;10138.188944884392;10508.789824203473;14322.785158222636;1909.6085302512952;1942.873713208651;1975.190172029461;1995.5609765851489;2034.7863474335277;83900.58049709712;87266;87275;87639;89672;10874.172715094133;11109.722256943642;11214.66356009321;11628.94185529067;12212.694682632828;15703.214839257991;15709.371625135094;15948.920255398649;16008.439704610282;16034.919825400799;7934.960325198337;7944.6027698614835;7973.72091976778;8023.598820058974;8052.15452377492;7818.100918394343;7870;7898.684052637945;8013.439059265796;8028.799280018046;4352.847650332224;4741.454732705783;4871.829485968045;4888.462269150391;4971.105201063771;1740.560931450638;1836.8395584002246;2012.9798702012793;2043.652579061563;2071.492484341346;888.9599968001326;937.5734041011324;976.3068221562628;1060.0989159214605;1061.124572227915;19246.133923973455;19369.806301937082;19457.22171113168;19781.307654232034;19936.70094948577;977.5405559387077;1007.385494848142;1025.43601019439;1027.4708525109575;1060.6287799270349;10415.323004004867;10919.454027298603;10954.726131846768;10994.835077473845;11000.284981476338;550022;552623;555401;558153;559517;60876.69561652163;67312.66343668348;85715.57142214249;92072;99975;981.7153025622632;995.835687111633;1011.7622358745689;1013.5591017907234;1021.7190272675074;17265.309387624857;17347.392841250497;17437.128143592516;18242;18904.243830246636;46465.76767116278;46956.53043469522;46999.765001174776;47804.52195478001;47872.80317992078;57163.283671632504;60683;61648.383516164264;64022.35977640164;64246.678766605866;8596.656133754579;8692.348073894356;8782.209601135928;8945.539976506512;9256.95371523138;10075.899241007495;13868.861311386758;14021.719565608835;14093.718125637635;14213.857861421253;12070.336131512851;12303.93848030754;12341.50633974667;12489.500419983098;12632.11575189728;11945.581904633644;12032;12055.276683399024;12071.456784444545;12236.694082648006;12214.877851221729;12417.565385211474;12480.750384992069;12490.250584964931;12510;8877.955610221907;8902.777430564287;8949.776255593402;8950.776230594027;9153.359264851397;10592.04047251261;12123.454444550192;12343.259404435763;12490.562830300896;12530.812037819622 21:59:02 INFO - PROCESS | 28389 | |3;mootools.html;1332.4670131947214;1506.9924650376747;1524.8627623513883;1701.6511615118902;1832.7067669172934;8387.496750194985;8762.605682744275;8775.648974041038;8865.246454051414;8960.775980600489;8745.650173993032;8899;8923.241524470412;8940.284777217823;8940.418872773265;2015.1737787507125;2230.353197572702;2302.2862912497094;2362.645603159527;2393.198278576679;2412.867292298923;2418.4316685578874;2425.9393515162174;2425.9878700606473;2429.1983645397063;2181.0512427094263;2240.8991595378166;2240.9215677451293;2241.1707668162803;2247.6179049561615;1967.2819420911335;2244.898979545916;2269.954600907989;2343.1915988983774;2350.9529809403716;2245.6406974884103;2252.279270633398;2265.818734501236;2273.147569661377;2273.499830037389;9004.234640055603;9126.224270936978;9147.131022552914;9147.862782058275;9150.679726209617;5167.09575824233;5192.818251361222;5244.527992480657;5249.632525723201;5256.395514515842;3207.342494788586;3219.6619354967625;3299.3566254580364;3314.6519615440275;3406.5401170842115;1017.7506510904801;1022.7545389106635;1028.953697083633;1032.1278519650882;1044.843273508972;12359.93820030903;12407.689807754788;12464.501419943192;12500.74998500034;12508.249505029728;4598.586127248497;4709.7174169549935;4727.787249573777;4744.217204161344;4896.632752543574;10560.630377936735;10574;10584;10584.89415105839;10626.309289896286;5479.260299859548;5488.286522752017;5490.533304669068;5498.477644623755;5502.339719233638;4165.8958526037095;4972.7513624318635;5034.84895453137;5050.823221187241;5145.305383773143;4067.9389809152885;4119.608637179523;4167.416561681367;4187.225363307798;4203.117345357448;1178.63462326679;1263.589333466627;1287.9375111800011;1323.589687196972;1324.410637266407;859.877859393485;922.9723108306628;946.3328353510758;960.4333443268505;1000.1298869983161;6379.681015949184;6408.51936104794;6432.8742470067555;6433.163688720437;6655.700493477708;1000.1298869983016;1054.8997845204694;1081.221520505235;1115.983260251097;1116.8045592021365;4495.348174514677;4580.450345958507;4602.516735742765;4625.814967401334;4705.55297246761;11014.614488492864;11371.886281137082;11394.430278486043;11413.372264525366;11492.023178029795;10536.683899483029;10945.671629851116;11334.489947952361;11337.1497137715;11360.545578176945;8412.495250285003;8529.616167272488;8544.871826922601;8555.230029297392;8596.269317108116;6522.641254731036;6744.865102698017;6778.4577233822165;6795.728170873307;6854.828629284308;10373.377597344184;10516.789664206826;10552.050315471644;10658.200634952254;10744.73138171552;10773.407462589634;10818.837717434402;10821.56713731442;10825.188110891871;10863.837042444527;7752.728654497066;7922.920770792449;7981.281684648177;7985.760427187193;7986.880196796937;8205.95897020511;8228.094909560063;8250.958745206235;8255.711050113217;8275.420720549504;6766.5490599793475;7035.613041282574;7671.213822293387;7888.290053895177;8141.552214628252;7943.84112317762;8007.239312265326;8027.678892844221;8031.196880311922;8109.189081091844;6899.862002759816;6915.723371065101;6919.792406227822;6923.376896079377;6933.653317334214;5017.54842064216;5267.552258058108;5388.892222155457;5448.291722076105;5473.781048758005;5370.543503802221;5421.674699518042;5493.697846618475;5516.9448305518035;5578.163275508706 21:59:02 INFO - PROCESS | 28389 | |4;prototype.html;314.4026349935124;319.1399179212023;321.800483700106;328.7813603953371;331.4613752651941;5323.520883120518;5334.466553344663;5354.73226338683;5369.5972802039805;5397.703126328055;5247.317848679676;5266.605004624659;5319.867003324919;5322.988632159887;5328.573714102872;3978.1049263915675;4038.3942408638645;4167.270727622669;4191.308434108384;4222.239996800575;4162.458880345551;4163.854265100722;4167.354060120686;4171.165766846643;4188.2409388057085;4010.8997275068064;4130.793460326987;4158;4168.124693814288;4175.144095460439;4011.6991225657903;4068.593140685952;4095.9590404095875;4126.442930204414;4164.6251837334785;3637.0907273181706;3674.3386190485703;3689.5572531296148;3703.166787472797;3716.2939041582285;5019.397672279313;5119.897602047976;5149.691018538862;5157.53582177602;5166.535011848914;4725.125851717409;4752.742447337521;4753.3582966299455;4755.690880092783;4784.64115191362;5411.945880541183;5414.07960646691;5414.7834086636485;5452.345718513764;5532.703927869929;3807.7144214184045;3917.001164703011;3933.429652700371;4043.0903046814524;4059.6346328830546;5619.8595035124445;5623.775048998035;5638.323401191883;5645.745941432645;5665.631733937322;4026.8993275168355;4029.5768944261017;4035.648057900636;4065.2276067547073;4074.2073487665334;3913.5890731473355;4108.8767336979345;4118.135191609796;4128.91742165155;4137.2552940471005;4694.460137084279;4721.409823772028;4726.054789042205;4747.501512341223;4751.904961900743;5549.19536667181;5575.860603484864;5575.944240557543;5616;5616.887662246732;1348.8853447457077;1399.846016938136;1412.8587141285789;1416.235232974203;1420.1621043584335;897.8967418746928;906.7551761024487;923.8521836506127;924.7423504034508;955.6082006377352;5299.920501192485;5307.6284660073425;5308.575313974873;5324.148136298174;5329.626926115135;5657.6322539035655;5664.631798933056;5665.99702157857;5670.716464176775;5711.25912527287;5086.949130508721;5258.763355649005;5295.285136506523;5310.336207974004;5329.747509335303;2978.776591755627;2982.6719060903283;2988.728566289592;3000.0999700089887;3002.8048176868433;5754.5971781975695;5772.3938986406665;5782.10377391505;5785.247917770747;5812.622179558315;5566.610337276352;5829.970850145722;5838.67887266196;5842.941570584325;5847.649141051551;5837.795677151279;5880.559872474651;5881.676507792113;5886.675498012913;5938.287405511366;5494.532964698045;5705.773258749389;5735.11105778605;5770.567207459373;5785.710714464344;5641.351244606923;5666.773329066791;5679.494933842618;5682.886342273048;5686.516646085129;5899.675517846388;5901.350851406428;5904.911426328523;5939.4357536034;5948.13752005948;3895.7272990890365;4074.8573799916862;4093.7339072960162;4103.733257338264;4117.3618089196225;5737.9139312911175;5739.426057394227;5752.6836024019085;5775.9133612995;5778.248827652378;5395.919061214164;5657.60396772222;5665.12190610456;5668.234788303691;5675.716214189191;5537.390887002346;5539.335279766454;5582.525485333792;5595.748191331319;5604.775808967595;5345.251664767014;5568.359638641446;5588.41321661236;5601.915971260516;5638.379778224474;5118.692878427306;5317.8404647860625;5482.835514934558;5540.418256083053;5580.832575022756;5459.945400545944;5486.670799752028;5487.4238204987905;5489.64317319373;5505.146702261156 21:59:02 INFO - PROCESS | 28389 | |5;yui.html;727.9490435669502;753.4273951796633;786.3394748411333;787.3347021766608;789.8657228271194;15197.240137993098;16789.496315110537;16815.579610509743;16893.746593801105;18233.36183233587;15641.139737314472;15646.530604081867;15790.289436975336;15811.604709882258;15907.761383579256;671.8085345676459;810.4853418079532;822.198356602314;823.8970128733908;823.9588020598961;840.0717207485732;840.7435732101707;845.5391811462767;846.1327139681845;846.8137009857821;883.5361435246483;884.4074470105045;885.9424137431079;886.1581497577295;886.8492356299412;704.1514974455769;852.288339236738;873.930085593151;874.8862647855738;875.4878396138234;634.1755717567145;634.7429291137097;634.7556190866503;635.2916498104627;635.6758053392757;14669.193194374306;14692.338844752012;14799.408023679147;14803.555893323217;14870.479533216394;12484.875151248461;13458.259795711232;14147.504837330644;14263.286835658279;14292.285385730776;13562.728745425135;13596.796048059286;13769.862301377058;13773.586792396243;13898;4439.800208990571;4457.888552786174;4458.487273963503;4501.324801279834;4537.863864084083;79976.20023799746;80130.59934700347;81957.9999999994;82275;82579.5871020647;11548.191626586058;11719.707007324714;11869.991050760782;11917.880821191851;12127.211731237441;20957;21247.256346027814;21253.36239912805;21268.78731212699;21858.672119918214;8267.087444573106;8279.62741676626;8376.246137847624;8562.186592273723;8749.212570868653;4471.105778844244;4480.171168333869;4535.682502224812;4635.605973492224;4780.856574302777;3441.0709108540555;3465.393556127718;3519.560054993126;3552.9163605100175;3556.928861422809;539.2665974274983;624.5409623926411;634.6604566556962;670.11210146556;704.3801454719916;620.0698951572641;682.5733916302311;774.1136398823428;778.4823092643442;783.4085265624417;9973.60105595768;10731.195160363004;10788.892111078789;10883.292585981886;11013.229073964905;654.0059110152637;676.6143298319911;733.7541923455595;752.0824593995317;790.9881351779728;4778.402699662543;5061.88401337926;5100.6429549931145;5122.769475373617;5162.716050617177;119406.83558986489;119901.40049299698;121422;122336;122479;106348.99999999846;106766;106774.46612766887;108093.4595327034;108638;90141;92442.07557924469;93498.0650193503;94151.52924235472;95769.52115239379;9505.382150160218;9508.524573771145;9577.281703872099;9625.278104142077;9735.41587504752;53057.46942530525;54128.75024836207;55040;55042.449575505336;56602.15096773634;74617.2538274632;77807.61096194483;82334;83838;84637;12290.446929888356;12694.984401247693;12912.418941147484;13229.603111906657;13370.465181392636;16023.759643605123;16063.277152528399;16083.437079702155;16667.83332166663;16933.661326773643;14351.425942962165;14699.11805291686;14705.705885882437;15059.096454212782;15855.356042076815;14177.220252885778;14423.49517766873;14560.563183104523;14679.41282348694;14774.48289309912;14265.786013209603;14267.928660357047;14468.782968255275;14480.782788257973;14644.19456929879;10808.481552474927;10823;11242.213045086766;11251.831222531833;11672.824907626222;12210.816837747263;12213.511459541518;12214.26714397139;12405.379731013594;12422.440990155645 21:59:02 INFO - PROCESS | 28389 | __end_tp_report 21:59:02 INFO - PROCESS | 28389 | __start_cc_report 21:59:02 INFO - PROCESS | 28389 | _x_x_mozilla_cycle_collect,825 21:59:02 INFO - PROCESS | 28389 | __end_cc_report 21:59:02 INFO - PROCESS | 28389 | __startTimestamp1468904342062__endTimestamp 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | ------- Summary: start ------- 21:59:02 INFO - PROCESS | 28389 | Number of tests: 6 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | [#0] dojo.html Cycles:165 Average:5299.89 Median:5548.50 stddev:608.50 (11.0%) stddev-sans-first:583.64 21:59:02 INFO - PROCESS | 28389 | Values: 3026.4 3053.6 3079.2 3080.8 3093.6 5256.6 5281.3 5298.2 5401.4 5425.7 5270.6 5292.4 5299.1 5317.0 5333.4 4766.2 4851.2 4866.0 4894.8 5065.6 4920.3 4930.0 4946.8 4994.6 5016.1 4783.4 4805.4 4816.8 4845.9 4850.4 4809.3 4810.4 4823.1 4833.8 4887.3 4815.0 4840.0 4840.9 4850.7 4891.0 5178.8 5225.7 5289.3 5293.5 5335.7 5727.2 5729.4 5731.1 5758.4 5765.1 5723.8 5773.1 5792.5 5802.5 5807.5 5229.8 5275.4 5318.5 5320.1 5401.4 5903.6 5903.9 5912.7 5915.1 5920.6 5506.2 5666.0 5679.7 5681.2 5683.5 5583.1 5647.5 5652.7 5748.9 5770.1 5671.5 5677.5 5679.8 5680.6 5694.2 5560.1 5621.1 5677.2 5688.8 5691.7 4292.5 4337.6 4372.4 4410.1 4411.7 3892.4 3893.1 3903.3 3908.4 3932.9 5230.5 5242.6 5324.5 5386.6 5423.1 5701.9 5729.0 5744.5 5747.1 5763.6 5332.9 5336.1 5340.6 5341.8 5347.3 4675.3 4766.3 4791.4 4821.4 4822.7 5923.7 5930.6 5933.9 5934.9 5935.4 5720.5 5730.3 5731.0 5739.6 5741.1 5723.4 5818.6 5819.0 5842.8 5906.1 5621.7 5633.8 5633.9 5634.4 5634.6 5568.7 5666.4 5702.1 5720.3 5722.5 5520.9 5544.3 5576.8 5590.1 5592.5 5585.3 5585.3 5590.9 5597.7 5598.9 5493.8 5505.3 5542.2 5589.2 5603.6 5594.8 5671.6 5704.4 5735.0 5740.7 5543.9 5548.5 5555.5 5557.5 5559.9 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | [#1] ext.html Cycles:180 Average:63996.93 Median:4172.22 stddev:133786.25 (3206.6%) stddev-sans-first:134076.80 21:59:02 INFO - PROCESS | 28389 | Values: 569.3 609.8 610.8 612.9 634.8 33088.3 37165.3 43780.8 44521.6 44697.6 46766.0 46949.3 47042.0 47190.8 47253.8 9092.9 9224.8 9242.9 9250.1 9256.6 9061.0 9138.8 9140.3 9146.8 9151.0 9238.6 9274.6 9279.4 9283.0 9285.7 9181.9 9202.0 9233.2 9233.8 9242.3 9033.8 9115.7 9118.2 9120.0 9120.5 44642.8 44722.8 44926.1 44926.6 44939.8 3511.9 3560.4 3979.2 4042.9 4185.8 31639.4 33729.5 34303.0 34391.0 34553.0 4008.6 4125.1 4153.5 4164.4 4180.0 396067.0 396478.0 398087.0 398168.0 398937.0 32624.7 63214.8 63512.7 63688.4 63829.4 34204.8 35778.8 36010.3 36037.5 36083.5 15942.8 16119.4 16146.4 16157.8 16162.3 13457.6 13487.8 13511.9 13519.5 13531.1 8443.2 8458.2 8476.7 8510.1 8553.3 3258.1 3300.2 3301.1 3302.1 3325.3 1012.9 1016.6 1037.9 1070.6 1102.8 33525.0 34028.5 34130.5 34149.5 34493.7 1110.0 1122.1 1133.9 1195.6 1229.8 17451.2 17488.8 17522.6 17907.2 17977.6 648079.0 652703.0 654089.0 655251.0 655921.0 61396.7 62724.4 62955.0 63188.4 63329.4 1254.6 1299.1 1362.3 1381.0 1389.8 31131.5 34730.7 34938.8 35063.8 35126.5 53242.0 53375.2 53936.0 54096.0 54104.0 409284.0 411589.0 411909.0 412553.0 413837.0 25338.0 28323.6 28533.4 28578.7 29615.1 50846.0 50889.5 51177.5 51689.5 52550.5 43402.6 44142.3 45060.3 45351.0 45896.5 43858.3 43910.1 44556.8 45042.1 45311.8 42657.4 42938.8 44198.8 44281.0 44569.3 37877.0 38477.8 38589.0 38630.2 39406.2 28822.3 28900.9 28970.6 29020.7 29552.9 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | [#2] jquery.html Cycles:180 Average:30825.83 Median:2637.44 stddev:91232.13 (3459.1%) stddev-sans-first:91459.43 21:59:02 INFO - PROCESS | 28389 | Values: 387.4 388.6 389.2 403.3 404.2 18558.3 19894.8 20586.5 20595.9 20599.2 12221.8 12508.1 12709.9 12990.2 13093.6 5317.8 5448.6 5506.4 5521.1 5523.8 3991.8 5731.4 5966.3 5969.0 6077.8 5766.2 6062.3 6062.5 6068.8 6083.5 6026.5 6096.7 6118.2 6126.5 6131.6 3886.8 5299.0 5556.4 5561.9 5562.6 14586.0 14588.0 19401.7 24432.8 24467.9 2407.4 2418.1 2459.6 2479.0 2795.9 9768.2 9931.4 10138.2 10508.8 14322.8 1909.6 1942.9 1975.2 1995.6 2034.8 83900.6 87266.0 87275.0 87639.0 89672.0 10874.2 11109.7 11214.7 11628.9 12212.7 15703.2 15709.4 15948.9 16008.4 16034.9 7935.0 7944.6 7973.7 8023.6 8052.2 7818.1 7870.0 7898.7 8013.4 8028.8 4352.8 4741.5 4871.8 4888.5 4971.1 1740.6 1836.8 2013.0 2043.7 2071.5 889.0 937.6 976.3 1060.1 1061.1 19246.1 19369.8 19457.2 19781.3 19936.7 977.5 1007.4 1025.4 1027.5 1060.6 10415.3 10919.5 10954.7 10994.8 11000.3 550022.0 552623.0 555401.0 558153.0 559517.0 60876.7 67312.7 85715.6 92072.0 99975.0 981.7 995.8 1011.8 1013.6 1021.7 17265.3 17347.4 17437.1 18242.0 18904.2 46465.8 46956.5 46999.8 47804.5 47872.8 57163.3 60683.0 61648.4 64022.4 64246.7 8596.7 8692.3 8782.2 8945.5 9257.0 10075.9 13868.9 14021.7 14093.7 14213.9 12070.3 12303.9 12341.5 12489.5 12632.1 11945.6 12032.0 12055.3 12071.5 12236.7 12214.9 12417.6 12480.8 12490.3 12510.0 8878.0 8902.8 8949.8 8950.8 9153.4 10592.0 12123.5 12343.3 12490.6 12530.8 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | [#3] mootools.html Cycles:180 Average:5956.30 Median:4614.17 stddev:3398.32 (73.6%) stddev-sans-first:3390.09 21:59:02 INFO - PROCESS | 28389 | Values: 1332.5 1507.0 1524.9 1701.7 1832.7 8387.5 8762.6 8775.6 8865.2 8960.8 8745.7 8899.0 8923.2 8940.3 8940.4 2015.2 2230.4 2302.3 2362.6 2393.2 2412.9 2418.4 2425.9 2426.0 2429.2 2181.1 2240.9 2240.9 2241.2 2247.6 1967.3 2244.9 2270.0 2343.2 2351.0 2245.6 2252.3 2265.8 2273.1 2273.5 9004.2 9126.2 9147.1 9147.9 9150.7 5167.1 5192.8 5244.5 5249.6 5256.4 3207.3 3219.7 3299.4 3314.7 3406.5 1017.8 1022.8 1029.0 1032.1 1044.8 12359.9 12407.7 12464.5 12500.7 12508.2 4598.6 4709.7 4727.8 4744.2 4896.6 10560.6 10574.0 10584.0 10584.9 10626.3 5479.3 5488.3 5490.5 5498.5 5502.3 4165.9 4972.8 5034.8 5050.8 5145.3 4067.9 4119.6 4167.4 4187.2 4203.1 1178.6 1263.6 1287.9 1323.6 1324.4 859.9 923.0 946.3 960.4 1000.1 6379.7 6408.5 6432.9 6433.2 6655.7 1000.1 1054.9 1081.2 1116.0 1116.8 4495.3 4580.5 4602.5 4625.8 4705.6 11014.6 11371.9 11394.4 11413.4 11492.0 10536.7 10945.7 11334.5 11337.1 11360.5 8412.5 8529.6 8544.9 8555.2 8596.3 6522.6 6744.9 6778.5 6795.7 6854.8 10373.4 10516.8 10552.1 10658.2 10744.7 10773.4 10818.8 10821.6 10825.2 10863.8 7752.7 7922.9 7981.3 7985.8 7986.9 8206.0 8228.1 8251.0 8255.7 8275.4 6766.5 7035.6 7671.2 7888.3 8141.6 7943.8 8007.2 8027.7 8031.2 8109.2 6899.9 6915.7 6919.8 6923.4 6933.7 5017.5 5267.6 5388.9 5448.3 5473.8 5370.5 5421.7 5493.7 5516.9 5578.2 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | [#4] prototype.html Cycles:180 Average:4674.03 Median:5329.10 stddev:1378.57 (25.9%) stddev-sans-first:1343.04 21:59:02 INFO - PROCESS | 28389 | Values: 314.4 319.1 321.8 328.8 331.5 5323.5 5334.5 5354.7 5369.6 5397.7 5247.3 5266.6 5319.9 5323.0 5328.6 3978.1 4038.4 4167.3 4191.3 4222.2 4162.5 4163.9 4167.4 4171.2 4188.2 4010.9 4130.8 4158.0 4168.1 4175.1 4011.7 4068.6 4096.0 4126.4 4164.6 3637.1 3674.3 3689.6 3703.2 3716.3 5019.4 5119.9 5149.7 5157.5 5166.5 4725.1 4752.7 4753.4 4755.7 4784.6 5411.9 5414.1 5414.8 5452.3 5532.7 3807.7 3917.0 3933.4 4043.1 4059.6 5619.9 5623.8 5638.3 5645.7 5665.6 4026.9 4029.6 4035.6 4065.2 4074.2 3913.6 4108.9 4118.1 4128.9 4137.3 4694.5 4721.4 4726.1 4747.5 4751.9 5549.2 5575.9 5575.9 5616.0 5616.9 1348.9 1399.8 1412.9 1416.2 1420.2 897.9 906.8 923.9 924.7 955.6 5299.9 5307.6 5308.6 5324.1 5329.6 5657.6 5664.6 5666.0 5670.7 5711.3 5086.9 5258.8 5295.3 5310.3 5329.7 2978.8 2982.7 2988.7 3000.1 3002.8 5754.6 5772.4 5782.1 5785.2 5812.6 5566.6 5830.0 5838.7 5842.9 5847.6 5837.8 5880.6 5881.7 5886.7 5938.3 5494.5 5705.8 5735.1 5770.6 5785.7 5641.4 5666.8 5679.5 5682.9 5686.5 5899.7 5901.4 5904.9 5939.4 5948.1 3895.7 4074.9 4093.7 4103.7 4117.4 5737.9 5739.4 5752.7 5775.9 5778.2 5395.9 5657.6 5665.1 5668.2 5675.7 5537.4 5539.3 5582.5 5595.7 5604.8 5345.3 5568.4 5588.4 5601.9 5638.4 5118.7 5317.8 5482.8 5540.4 5580.8 5459.9 5486.7 5487.4 5489.6 5505.1 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - PROCESS | 28389 | [#5] yui.html Cycles:180 Average:22114.29 Median:4458.19 stddev:32084.13 (719.7%) stddev-sans-first:32133.94 21:59:02 INFO - PROCESS | 28389 | Values: 727.9 753.4 786.3 787.3 789.9 15197.2 16789.5 16815.6 16893.7 18233.4 15641.1 15646.5 15790.3 15811.6 15907.8 671.8 810.5 822.2 823.9 824.0 840.1 840.7 845.5 846.1 846.8 883.5 884.4 885.9 886.2 886.8 704.2 852.3 873.9 874.9 875.5 634.2 634.7 634.8 635.3 635.7 14669.2 14692.3 14799.4 14803.6 14870.5 12484.9 13458.3 14147.5 14263.3 14292.3 13562.7 13596.8 13769.9 13773.6 13898.0 4439.8 4457.9 4458.5 4501.3 4537.9 79976.2 80130.6 81958.0 82275.0 82579.6 11548.2 11719.7 11870.0 11917.9 12127.2 20957.0 21247.3 21253.4 21268.8 21858.7 8267.1 8279.6 8376.2 8562.2 8749.2 4471.1 4480.2 4535.7 4635.6 4780.9 3441.1 3465.4 3519.6 3552.9 3556.9 539.3 624.5 634.7 670.1 704.4 620.1 682.6 774.1 778.5 783.4 9973.6 10731.2 10788.9 10883.3 11013.2 654.0 676.6 733.8 752.1 791.0 4778.4 5061.9 5100.6 5122.8 5162.7 119406.8 119901.4 121422.0 122336.0 122479.0 106349.0 106766.0 106774.5 108093.5 108638.0 90141.0 92442.1 93498.1 94151.5 95769.5 9505.4 9508.5 9577.3 9625.3 9735.4 53057.5 54128.8 55040.0 55042.4 56602.2 74617.3 77807.6 82334.0 83838.0 84637.0 12290.4 12695.0 12912.4 13229.6 13370.5 16023.8 16063.3 16083.4 16667.8 16933.7 14351.4 14699.1 14705.7 15059.1 15855.4 14177.2 14423.5 14560.6 14679.4 14774.5 14265.8 14267.9 14468.8 14480.8 14644.2 10808.5 10823.0 11242.2 11251.8 11672.8 12210.8 12213.5 12214.3 12405.4 12422.4 21:59:02 INFO - PROCESS | 28389 | -------- Summary: end -------- 21:59:02 INFO - PROCESS | 28389 | 21:59:02 INFO - TEST-INFO | 28389: exit 0 21:59:02 INFO - TEST-OK | dromaeo_css | took 1112898ms 21:59:02 INFO - TEST-START | kraken 21:59:02 INFO - operating with platform_type : linux_ 21:59:02 INFO - Initialising browser for kraken test... 21:59:02 INFO - TEST-INFO | started process 28570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEV2Ftj/profile http://localhost:47852/getInfo.html) 21:59:06 INFO - PROCESS | 28570 | __metrics Screen width/height:1600/1200 21:59:06 INFO - PROCESS | 28570 | colorDepth:24 21:59:06 INFO - PROCESS | 28570 | Browser inner width/height: 1024/697 21:59:06 INFO - PROCESS | 28570 | __metrics 21:59:07 INFO - PROCESS | 28570 | 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" 21:59:07 INFO - PROCESS | 28570 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:59:08 INFO - PROCESS | 28570 | console.error: PushService: 21:59:08 INFO - PROCESS | 28570 | stateChangeProcessEnqueue: Error transitioning state 21:59:08 INFO - PROCESS | 28570 | UnknownError 21:59:08 INFO - TEST-INFO | 28570: exit 0 21:59:08 INFO - Browser initialized. 21:59:08 INFO - Running cycle 1/1 for kraken test... 21:59:08 INFO - TEST-INFO | started process 28651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEV2Ftj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:59:20 INFO - PROCESS | 28651 | RSS: Main: 158404608 21:59:20 INFO - PROCESS | 28651 | 21:59:23 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47852/tests/kraken/driver.html?testName=audio-beat-detection) 21:59:23 INFO - PROCESS | 28651 | RSS: Main: 217063424 21:59:23 INFO - PROCESS | 28651 | 21:59:26 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47852/tests/kraken/driver.html?testName=audio-dft) 21:59:27 INFO - PROCESS | 28651 | RSS: Main: 205746176 21:59:27 INFO - PROCESS | 28651 | 21:59:30 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47852/tests/kraken/driver.html?testName=audio-fft) 21:59:30 INFO - PROCESS | 28651 | RSS: Main: 270131200 21:59:30 INFO - PROCESS | 28651 | 21:59:32 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47852/tests/kraken/driver.html?testName=audio-oscillator) 21:59:33 INFO - PROCESS | 28651 | RSS: Main: 181755904 21:59:33 INFO - PROCESS | 28651 | 21:59:35 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47852/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:59:36 INFO - PROCESS | 28651 | RSS: Main: 185643008 21:59:36 INFO - PROCESS | 28651 | 21:59:39 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47852/tests/kraken/driver.html?testName=imaging-darkroom) 21:59:39 INFO - PROCESS | 28651 | RSS: Main: 219754496 21:59:39 INFO - PROCESS | 28651 | 21:59:43 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47852/tests/kraken/driver.html?testName=imaging-desaturate) 21:59:43 INFO - PROCESS | 28651 | RSS: Main: 221339648 21:59:43 INFO - PROCESS | 28651 | 21:59:46 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47852/tests/kraken/driver.html?testName=json-parse-financial) 21:59:47 INFO - PROCESS | 28651 | RSS: Main: 222150656 21:59:47 INFO - PROCESS | 28651 | 21:59:48 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47852/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:59:49 INFO - PROCESS | 28651 | RSS: Main: 210235392 21:59:49 INFO - PROCESS | 28651 | 21:59:51 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:59:51 INFO - PROCESS | 28651 | RSS: Main: 214994944 21:59:51 INFO - PROCESS | 28651 | 21:59:53 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:59:54 INFO - PROCESS | 28651 | RSS: Main: 213098496 21:59:54 INFO - PROCESS | 28651 | 21:59:56 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:59:57 INFO - PROCESS | 28651 | RSS: Main: 226390016 21:59:57 INFO - PROCESS | 28651 | 22:00:00 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:00:00 INFO - PROCESS | 28651 | RSS: Main: 227291136 22:00:00 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | Cycle 1(1): loaded http://localhost:47852/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47852/tests/kraken/driver.html?testName=ai-astar) 22:00:02 INFO - PROCESS | 28651 | RSS: Main: 269680640 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | __start_tp_report 22:00:02 INFO - PROCESS | 28651 | _x_x_mozilla_page_load 22:00:02 INFO - PROCESS | 28651 | _x_x_mozilla_page_load_details 22:00:02 INFO - PROCESS | 28651 | |i|pagename|runs| 22:00:02 INFO - PROCESS | 28651 | |0;ai-astar;102;111;109;110;111;111;111;109;108;109 22:00:02 INFO - PROCESS | 28651 | |1;audio-beat-detection;145;116;120;167;121;123;144;116;144;117 22:00:02 INFO - PROCESS | 28651 | |2;audio-dft;161;162;159;162;161;160;160;162;161;164 22:00:02 INFO - PROCESS | 28651 | |3;audio-fft;74;74;74;121;75;76;75;75;105;75 22:00:02 INFO - PROCESS | 28651 | |4;audio-oscillator;83;84;111;83;84;84;123;84;84;127 22:00:02 INFO - PROCESS | 28651 | |5;imaging-gaussian-blur;116;117;117;117;116;117;116;117;116;117 22:00:02 INFO - PROCESS | 28651 | |6;imaging-darkroom;146;145;145;145;145;146;145;145;145;146 22:00:02 INFO - PROCESS | 28651 | |7;imaging-desaturate;94;96;95;95;95;95;95;95;95;95 22:00:02 INFO - PROCESS | 28651 | |8;json-parse-financial;51;50;50;50;50;51;51;50;50;50 22:00:02 INFO - PROCESS | 28651 | |9;json-stringify-tinderbox;61;63;63;62;62;61;61;62;63;62 22:00:02 INFO - PROCESS | 28651 | |10;stanford-crypto-aes;78;82;75;75;75;78;78;74;75;84 22:00:02 INFO - PROCESS | 28651 | |11;stanford-crypto-ccm;118;121;119;130;118;120;120;127;171;129 22:00:02 INFO - PROCESS | 28651 | |12;stanford-crypto-pbkdf2;157;161;160;156;155;161;155;163;153;154 22:00:02 INFO - PROCESS | 28651 | |13;stanford-crypto-sha256-iterative;62;62;61;60;62;62;63;64;65;60 22:00:02 INFO - PROCESS | 28651 | __end_tp_report 22:00:02 INFO - PROCESS | 28651 | __start_cc_report 22:00:02 INFO - PROCESS | 28651 | _x_x_mozilla_cycle_collect,1661 22:00:02 INFO - PROCESS | 28651 | __end_cc_report 22:00:02 INFO - PROCESS | 28651 | __startTimestamp1468904402679__endTimestamp 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | ------- Summary: start ------- 22:00:02 INFO - PROCESS | 28651 | Number of tests: 14 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.50 stddev:2.73 (2.5%) stddev-sans-first:1.17 22:00:02 INFO - PROCESS | 28651 | Values: 102.0 111.0 109.0 110.0 111.0 111.0 111.0 109.0 108.0 109.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:133.50 stddev:17.51 (13.1%) stddev-sans-first:17.86 22:00:02 INFO - PROCESS | 28651 | Values: 145.0 116.0 120.0 167.0 121.0 123.0 144.0 116.0 144.0 117.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.40 (0.9%) stddev-sans-first:1.48 22:00:02 INFO - PROCESS | 28651 | Values: 161.0 162.0 159.0 162.0 161.0 160.0 160.0 162.0 161.0 164.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#3] audio-fft Cycles:10 Average:82.40 Median:75.00 stddev:16.57 (22.1%) stddev-sans-first:17.30 22:00:02 INFO - PROCESS | 28651 | Values: 74.0 74.0 74.0 121.0 75.0 76.0 75.0 75.0 105.0 75.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.00 stddev:18.12 (21.6%) stddev-sans-first:18.72 22:00:02 INFO - PROCESS | 28651 | Values: 83.0 84.0 111.0 83.0 84.0 84.0 123.0 84.0 84.0 127.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 22:00:02 INFO - PROCESS | 28651 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 22:00:02 INFO - PROCESS | 28651 | Values: 146.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 22:00:02 INFO - PROCESS | 28651 | Values: 94.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:0.48 (1.0%) stddev-sans-first:0.44 22:00:02 INFO - PROCESS | 28651 | Values: 51.0 50.0 50.0 50.0 50.0 51.0 51.0 50.0 50.0 50.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 22:00:02 INFO - PROCESS | 28651 | Values: 61.0 63.0 63.0 62.0 62.0 61.0 61.0 62.0 63.0 62.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#10] stanford-crypto-aes Cycles:10 Average:77.40 Median:78.00 stddev:3.34 (4.3%) stddev-sans-first:3.54 22:00:02 INFO - PROCESS | 28651 | Values: 78.0 82.0 75.0 75.0 75.0 78.0 78.0 74.0 75.0 84.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:124.00 stddev:16.01 (12.9%) stddev-sans-first:16.63 22:00:02 INFO - PROCESS | 28651 | Values: 118.0 121.0 119.0 130.0 118.0 120.0 120.0 127.0 171.0 129.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:158.50 stddev:3.47 (2.2%) stddev-sans-first:3.68 22:00:02 INFO - PROCESS | 28651 | Values: 157.0 161.0 160.0 156.0 155.0 161.0 155.0 163.0 153.0 154.0 22:00:02 INFO - PROCESS | 28651 | 22:00:02 INFO - PROCESS | 28651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:62.00 stddev:1.60 (2.6%) stddev-sans-first:1.69 22:00:02 INFO - PROCESS | 28651 | Values: 62.0 62.0 61.0 60.0 62.0 62.0 63.0 64.0 65.0 60.0 22:00:02 INFO - PROCESS | 28651 | -------- Summary: end -------- 22:00:02 INFO - PROCESS | 28651 | 22:00:03 INFO - TEST-INFO | 28651: exit 0 22:00:03 INFO - TEST-OK | kraken | took 60899ms 22:00:03 INFO - SUITE-END | took 1173s 22:00:03 INFO - Completed test suite (00:19:33) 22:00:03 INFO - javascript benchmark 22:00:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3026.379592183602, 3053.5572342010396, 3079.230192451887, 3080.8151510909347, 3093.613298337708, 5256.579473642109, 5281.339832520935, 5298.178782288742, 5401.405845357012, 5425.701586412751, 5270.578353731702, 5292.444293349201, 5299.125644268701, 5317.0, 5333.38666053404, 4766.189747742892, 4851.199552073921, 4865.975670121645, 4894.804207831682, 5065.594752419797, 4920.3111564381015, 4930.03864246472, 4946.777395017215, 4994.575461085812, 5016.122178618745, 4783.402074740658, 4805.447373552034, 4816.759162041919, 4845.927311090337, 4850.3937007873665, 4809.254565542346, 4810.350602668665, 4823.059503396839, 4833.806647734086, 4887.3402090717655, 4815.0, 4839.951600484021, 4840.878978025542, 4850.733209673466, 4890.997345544155, 5178.844634660966, 5225.6603320784025, 5289.3123893893935, 5293.523582877559, 5335.653182543122, 5727.1695604137585, 5729.369769325371, 5731.111677689923, 5758.395368486333, 5765.077587585966, 5723.771049158028, 5773.134029895507, 5792.478676919055, 5802.506786923074, 5807.506361959238, 5229.790808367661, 5275.393329767049, 5318.494742999409, 5320.14877619579, 5401.378841433286, 5903.645781253139, 5903.911441328383, 5912.733926973297, 5915.053591425351, 5920.644761314335, 5506.229127922094, 5666.0, 5679.659220446786, 5681.204631351614, 5683.54531637468, 5583.106702927512, 5647.463490968352, 5652.689102099342, 5748.9425105748405, 5770.10563362671, 5671.489565939085, 5677.460641239076, 5679.829605111853, 5680.602357834912, 5694.202811606378, 5560.054790685513, 5621.072523033736, 5677.233573467612, 5688.829335119953, 5691.686957217393, 4292.463442069741, 4337.566243375701, 4372.387865698741, 4410.07388448423, 4411.669124815588, 3892.4355968384425, 3893.0851249956236, 3903.316919539126, 3908.355121404993, 3932.8820135395986, 5230.503102205361, 5242.633015688865, 5324.547413469821, 5386.596005299618, 5423.078076726966, 5701.914471282934, 5728.971355143281, 5744.511716504144, 5747.08046712524, 5763.625364351304, 5332.893342133213, 5336.092864213016, 5340.572754179656, 5341.759620817073, 5347.3048503695045, 4675.251959686502, 4766.285057241442, 4791.401074865642, 4821.445533763592, 4822.662413631012, 5923.733431995571, 5930.584859059911, 5933.940660593339, 5934.910976335358, 5935.376785437553, 5720.456556627114, 5730.255066841284, 5730.9713451434145, 5739.598228123991, 5741.1388291756575, 5723.399043100415, 5818.592698511064, 5819.039858423482, 5842.766289348378, 5906.114082887601, 5621.718914054362, 5633.774649013993, 5633.943660563342, 5634.380218176079, 5634.577406694432, 5568.6658800472105, 5666.433356664301, 5702.059160238514, 5720.2849643794525, 5722.484976352148, 5520.86197845041, 5544.3346798384455, 5576.832695019156, 5590.077637189981, 5592.468715472024, 5585.329760428612, 5585.3297604287745, 5590.86022849432, 5597.692126932895, 5598.86002849932, 5493.752781124939, 5505.256790333414, 5542.201188399832, 5589.189567512606, 5603.551715862803, 5594.776208951596, 5671.631343962629, 5704.372519022987, 5735.025045742317, 5740.655560666373, 5543.9168412474655, 5548.500634942875, 5555.527780138734, 5557.472040156179, 5559.916601250903], "value": 5258.285768581083, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.3424095170077, 609.8048624440175, 610.7648555306206, 612.9172561704171, 634.847636567224, 33088.33823323532, 37165.25669486612, 43780.78109609451, 44521.55478445214, 44697.553024469744, 46766.0, 46949.29576056362, 47042.0, 47190.76404617972, 47253.76373118138, 9092.863607045883, 9224.769380765467, 9242.907570924272, 9250.121238482334, 9256.6297348106, 9061.0, 9138.817223655524, 9140.314476414262, 9146.817063658722, 9150.954245228797, 9238.6304547818, 9274.582643781012, 9279.350445468819, 9283.0, 9285.675001374953, 9181.908180918172, 9202.0, 9233.215176709988, 9233.815323693556, 9242.260619150486, 9033.819323613556, 9115.680951166678, 9118.179363857285, 9119.954400228024, 9120.45277283365, 44642.776786115865, 44722.77638611786, 44926.10147797059, 44926.550734492565, 44939.775301123285, 3511.929761404783, 3560.430331147004, 3979.243943650697, 4042.939355909664, 4185.769782661952, 31639.36721265562, 33729.49405758915, 34303.0, 34391.0, 34553.0, 4008.6392224699916, 4125.071858831769, 4153.522344930341, 4164.437800896901, 4180.0, 396067.0, 396478.0, 398087.0, 398168.0, 398937.0, 32624.673753262163, 63214.83925803691, 63512.682436588446, 63688.36311636917, 63829.36170638327, 34204.82897585546, 35778.821105894305, 36010.27979440449, 36037.459438108446, 36083.4587481188, 15942.760858587131, 16119.435819746253, 16146.354145834035, 16157.757633635505, 16162.272697728631, 13457.596272111852, 13487.797683034762, 13511.864881351257, 13519.459221631221, 13531.05282630226, 8443.197896199852, 8458.196471335214, 8476.745697629081, 8510.106438823957, 8553.315734741205, 3258.1365938026443, 3300.174956260935, 3301.059198128522, 3302.1249368917256, 3325.285063711314, 1012.9341592796592, 1016.6238491758097, 1037.9257468520045, 1070.6466865934224, 1102.8070087734775, 33525.0, 34028.48957265643, 34130.48804267938, 34149.48775768365, 34493.65506344954, 1109.9919218916825, 1122.1247427006947, 1133.9092872570175, 1195.5934982106126, 1229.8032314829584, 17451.214695338742, 17488.825111748974, 17522.649547008987, 17907.1941762621, 17977.640447190985, 648079.0, 652703.0, 654089.0, 655251.0, 655921.0, 61396.693016534635, 62724.372756272765, 62955.0, 63188.36811631825, 63329.36670633327, 1254.5797157952006, 1299.1295831792722, 1362.264377236301, 1380.951666691681, 1389.8471168171493, 31131.53302700416, 34730.652693472744, 34938.82530587331, 35063.82468087745, 35126.47310290399, 53242.0, 53375.199372008676, 53936.0, 54096.0, 54104.0, 409284.0, 411589.0, 411909.0, 412553.0, 413837.0, 25338.0, 28323.57514637241, 28533.42933141284, 28578.714212857605, 29615.111546653636, 50846.0, 50889.49110508848, 51177.488225117275, 51689.48310516847, 52550.47449525456, 43402.56597433985, 44142.33786493269, 45060.32409513794, 45351.0, 45896.54103459056, 43858.34212486879, 43910.12179756451, 44556.77721611371, 45042.099158016, 45311.773441133904, 42657.36013959855, 42938.78530607327, 44198.77900610476, 44281.0, 44569.33146002878, 37877.0, 38477.80761096177, 38589.0, 38630.22739545137, 39406.2118757629, 28822.279443014093, 28900.915532360996, 28970.565441518815, 29020.709792902646, 29552.852235739545], "value": 19685.912786468984, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.4459522882279, 388.59197842265615, 389.23321057516694, 403.3344980781712, 404.2238901309486, 18558.257669693212, 19894.801051989474, 20586.48533786656, 20595.897020514876, 20599.176032958683, 12221.816672749894, 12508.06189535784, 12709.936450317758, 12990.2205867648, 13093.607191784238, 5317.7607007684555, 5448.645838020516, 5506.366767821692, 5521.1442226454765, 5523.806666766664, 3991.800409979504, 5731.369549349568, 5966.254218222723, 5969.0, 6077.817665470043, 5766.192733017381, 6062.302835173968, 6062.514998800108, 6068.817935461943, 6083.513318934474, 6026.517878569726, 6096.72564734588, 6118.235220597426, 6126.47924926384, 6131.570790044699, 3886.805659717003, 5299.0, 5556.444355564452, 5561.944380556183, 5562.554995600382, 14586.0, 14588.0, 19401.708974365396, 24432.755672443225, 24467.87766061176, 2407.3500154958238, 2418.093215044358, 2459.557279689655, 2479.0, 2795.8741856616502, 9768.21854251658, 9931.404115753006, 10138.188944884392, 10508.789824203473, 14322.785158222636, 1909.6085302512952, 1942.873713208651, 1975.190172029461, 1995.5609765851489, 2034.7863474335277, 83900.58049709712, 87266.0, 87275.0, 87639.0, 89672.0, 10874.172715094133, 11109.722256943642, 11214.66356009321, 11628.94185529067, 12212.694682632828, 15703.214839257991, 15709.371625135094, 15948.920255398649, 16008.439704610282, 16034.919825400799, 7934.960325198337, 7944.6027698614835, 7973.72091976778, 8023.598820058974, 8052.15452377492, 7818.100918394343, 7870.0, 7898.684052637945, 8013.439059265796, 8028.799280018046, 4352.847650332224, 4741.454732705783, 4871.829485968045, 4888.462269150391, 4971.105201063771, 1740.560931450638, 1836.8395584002246, 2012.9798702012793, 2043.652579061563, 2071.492484341346, 888.9599968001326, 937.5734041011324, 976.3068221562628, 1060.0989159214605, 1061.124572227915, 19246.133923973455, 19369.806301937082, 19457.22171113168, 19781.307654232034, 19936.70094948577, 977.5405559387077, 1007.385494848142, 1025.43601019439, 1027.4708525109575, 1060.6287799270349, 10415.323004004867, 10919.454027298603, 10954.726131846768, 10994.835077473845, 11000.284981476338, 550022.0, 552623.0, 555401.0, 558153.0, 559517.0, 60876.69561652163, 67312.66343668348, 85715.57142214249, 92072.0, 99975.0, 981.7153025622632, 995.835687111633, 1011.7622358745689, 1013.5591017907234, 1021.7190272675074, 17265.309387624857, 17347.392841250497, 17437.128143592516, 18242.0, 18904.243830246636, 46465.76767116278, 46956.53043469522, 46999.765001174776, 47804.52195478001, 47872.80317992078, 57163.283671632504, 60683.0, 61648.383516164264, 64022.35977640164, 64246.678766605866, 8596.656133754579, 8692.348073894356, 8782.209601135928, 8945.539976506512, 9256.95371523138, 10075.899241007495, 13868.861311386758, 14021.719565608835, 14093.718125637635, 14213.857861421253, 12070.336131512851, 12303.93848030754, 12341.50633974667, 12489.500419983098, 12632.11575189728, 11945.581904633644, 12032.0, 12055.276683399024, 12071.456784444545, 12236.694082648006, 12214.877851221729, 12417.565385211474, 12480.750384992069, 12490.250584964931, 12510.0, 8877.955610221907, 8902.777430564287, 8949.776255593402, 8950.776230594027, 9153.359264851397, 10592.04047251261, 12123.454444550192, 12343.259404435763, 12490.562830300896, 12530.812037819622], "value": 9274.920097887045, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.4670131947214, 1506.9924650376747, 1524.8627623513883, 1701.6511615118902, 1832.7067669172934, 8387.496750194985, 8762.605682744275, 8775.648974041038, 8865.246454051414, 8960.775980600489, 8745.650173993032, 8899.0, 8923.241524470412, 8940.284777217823, 8940.418872773265, 2015.1737787507125, 2230.353197572702, 2302.2862912497094, 2362.645603159527, 2393.198278576679, 2412.867292298923, 2418.4316685578874, 2425.9393515162174, 2425.9878700606473, 2429.1983645397063, 2181.0512427094263, 2240.8991595378166, 2240.9215677451293, 2241.1707668162803, 2247.6179049561615, 1967.2819420911335, 2244.898979545916, 2269.954600907989, 2343.1915988983774, 2350.9529809403716, 2245.6406974884103, 2252.279270633398, 2265.818734501236, 2273.147569661377, 2273.499830037389, 9004.234640055603, 9126.224270936978, 9147.131022552914, 9147.862782058275, 9150.679726209617, 5167.09575824233, 5192.818251361222, 5244.527992480657, 5249.632525723201, 5256.395514515842, 3207.342494788586, 3219.6619354967625, 3299.3566254580364, 3314.6519615440275, 3406.5401170842115, 1017.7506510904801, 1022.7545389106635, 1028.953697083633, 1032.1278519650882, 1044.843273508972, 12359.93820030903, 12407.689807754788, 12464.501419943192, 12500.74998500034, 12508.249505029728, 4598.586127248497, 4709.7174169549935, 4727.787249573777, 4744.217204161344, 4896.632752543574, 10560.630377936735, 10574.0, 10584.0, 10584.89415105839, 10626.309289896286, 5479.260299859548, 5488.286522752017, 5490.533304669068, 5498.477644623755, 5502.339719233638, 4165.8958526037095, 4972.7513624318635, 5034.84895453137, 5050.823221187241, 5145.305383773143, 4067.9389809152885, 4119.608637179523, 4167.416561681367, 4187.225363307798, 4203.117345357448, 1178.63462326679, 1263.589333466627, 1287.9375111800011, 1323.589687196972, 1324.410637266407, 859.877859393485, 922.9723108306628, 946.3328353510758, 960.4333443268505, 1000.1298869983161, 6379.681015949184, 6408.51936104794, 6432.8742470067555, 6433.163688720437, 6655.700493477708, 1000.1298869983016, 1054.8997845204694, 1081.221520505235, 1115.983260251097, 1116.8045592021365, 4495.348174514677, 4580.450345958507, 4602.516735742765, 4625.814967401334, 4705.55297246761, 11014.614488492864, 11371.886281137082, 11394.430278486043, 11413.372264525366, 11492.023178029795, 10536.683899483029, 10945.671629851116, 11334.489947952361, 11337.1497137715, 11360.545578176945, 8412.495250285003, 8529.616167272488, 8544.871826922601, 8555.230029297392, 8596.269317108116, 6522.641254731036, 6744.865102698017, 6778.4577233822165, 6795.728170873307, 6854.828629284308, 10373.377597344184, 10516.789664206826, 10552.050315471644, 10658.200634952254, 10744.73138171552, 10773.407462589634, 10818.837717434402, 10821.56713731442, 10825.188110891871, 10863.837042444527, 7752.728654497066, 7922.920770792449, 7981.281684648177, 7985.760427187193, 7986.880196796937, 8205.95897020511, 8228.094909560063, 8250.958745206235, 8255.711050113217, 8275.420720549504, 6766.5490599793475, 7035.613041282574, 7671.213822293387, 7888.290053895177, 8141.552214628252, 7943.84112317762, 8007.239312265326, 8027.678892844221, 8031.196880311922, 8109.189081091844, 6899.862002759816, 6915.723371065101, 6919.792406227822, 6923.376896079377, 6933.653317334214, 5017.54842064216, 5267.552258058108, 5388.892222155457, 5448.291722076105, 5473.781048758005, 5370.543503802221, 5421.674699518042, 5493.697846618475, 5516.9448305518035, 5578.163275508706], "value": 4740.068404146548, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.4026349935124, 319.1399179212023, 321.800483700106, 328.7813603953371, 331.4613752651941, 5323.520883120518, 5334.466553344663, 5354.73226338683, 5369.5972802039805, 5397.703126328055, 5247.317848679676, 5266.605004624659, 5319.867003324919, 5322.988632159887, 5328.573714102872, 3978.1049263915675, 4038.3942408638645, 4167.270727622669, 4191.308434108384, 4222.239996800575, 4162.458880345551, 4163.854265100722, 4167.354060120686, 4171.165766846643, 4188.2409388057085, 4010.8997275068064, 4130.793460326987, 4158.0, 4168.124693814288, 4175.144095460439, 4011.6991225657903, 4068.593140685952, 4095.9590404095875, 4126.442930204414, 4164.6251837334785, 3637.0907273181706, 3674.3386190485703, 3689.5572531296148, 3703.166787472797, 3716.2939041582285, 5019.397672279313, 5119.897602047976, 5149.691018538862, 5157.53582177602, 5166.535011848914, 4725.125851717409, 4752.742447337521, 4753.3582966299455, 4755.690880092783, 4784.64115191362, 5411.945880541183, 5414.07960646691, 5414.7834086636485, 5452.345718513764, 5532.703927869929, 3807.7144214184045, 3917.001164703011, 3933.429652700371, 4043.0903046814524, 4059.6346328830546, 5619.8595035124445, 5623.775048998035, 5638.323401191883, 5645.745941432645, 5665.631733937322, 4026.8993275168355, 4029.5768944261017, 4035.648057900636, 4065.2276067547073, 4074.2073487665334, 3913.5890731473355, 4108.8767336979345, 4118.135191609796, 4128.91742165155, 4137.2552940471005, 4694.460137084279, 4721.409823772028, 4726.054789042205, 4747.501512341223, 4751.904961900743, 5549.19536667181, 5575.860603484864, 5575.944240557543, 5616.0, 5616.887662246732, 1348.8853447457077, 1399.846016938136, 1412.8587141285789, 1416.235232974203, 1420.1621043584335, 897.8967418746928, 906.7551761024487, 923.8521836506127, 924.7423504034508, 955.6082006377352, 5299.920501192485, 5307.6284660073425, 5308.575313974873, 5324.148136298174, 5329.626926115135, 5657.6322539035655, 5664.631798933056, 5665.99702157857, 5670.716464176775, 5711.25912527287, 5086.949130508721, 5258.763355649005, 5295.285136506523, 5310.336207974004, 5329.747509335303, 2978.776591755627, 2982.6719060903283, 2988.728566289592, 3000.0999700089887, 3002.8048176868433, 5754.5971781975695, 5772.3938986406665, 5782.10377391505, 5785.247917770747, 5812.622179558315, 5566.610337276352, 5829.970850145722, 5838.67887266196, 5842.941570584325, 5847.649141051551, 5837.795677151279, 5880.559872474651, 5881.676507792113, 5886.675498012913, 5938.287405511366, 5494.532964698045, 5705.773258749389, 5735.11105778605, 5770.567207459373, 5785.710714464344, 5641.351244606923, 5666.773329066791, 5679.494933842618, 5682.886342273048, 5686.516646085129, 5899.675517846388, 5901.350851406428, 5904.911426328523, 5939.4357536034, 5948.13752005948, 3895.7272990890365, 4074.8573799916862, 4093.7339072960162, 4103.733257338264, 4117.3618089196225, 5737.9139312911175, 5739.426057394227, 5752.6836024019085, 5775.9133612995, 5778.248827652378, 5395.919061214164, 5657.60396772222, 5665.12190610456, 5668.234788303691, 5675.716214189191, 5537.390887002346, 5539.335279766454, 5582.525485333792, 5595.748191331319, 5604.775808967595, 5345.251664767014, 5568.359638641446, 5588.41321661236, 5601.915971260516, 5638.379778224474, 5118.692878427306, 5317.8404647860625, 5482.835514934558, 5540.418256083053, 5580.832575022756, 5459.945400545944, 5486.670799752028, 5487.4238204987905, 5489.64317319373, 5505.146702261156], "value": 4229.2146421489915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.9490435669502, 753.4273951796633, 786.3394748411333, 787.3347021766608, 789.8657228271194, 15197.240137993098, 16789.496315110537, 16815.579610509743, 16893.746593801105, 18233.36183233587, 15641.139737314472, 15646.530604081867, 15790.289436975336, 15811.604709882258, 15907.761383579256, 671.8085345676459, 810.4853418079532, 822.198356602314, 823.8970128733908, 823.9588020598961, 840.0717207485732, 840.7435732101707, 845.5391811462767, 846.1327139681845, 846.8137009857821, 883.5361435246483, 884.4074470105045, 885.9424137431079, 886.1581497577295, 886.8492356299412, 704.1514974455769, 852.288339236738, 873.930085593151, 874.8862647855738, 875.4878396138234, 634.1755717567145, 634.7429291137097, 634.7556190866503, 635.2916498104627, 635.6758053392757, 14669.193194374306, 14692.338844752012, 14799.408023679147, 14803.555893323217, 14870.479533216394, 12484.875151248461, 13458.259795711232, 14147.504837330644, 14263.286835658279, 14292.285385730776, 13562.728745425135, 13596.796048059286, 13769.862301377058, 13773.586792396243, 13898.0, 4439.800208990571, 4457.888552786174, 4458.487273963503, 4501.324801279834, 4537.863864084083, 79976.20023799746, 80130.59934700347, 81957.9999999994, 82275.0, 82579.5871020647, 11548.191626586058, 11719.707007324714, 11869.991050760782, 11917.880821191851, 12127.211731237441, 20957.0, 21247.256346027814, 21253.36239912805, 21268.78731212699, 21858.672119918214, 8267.087444573106, 8279.62741676626, 8376.246137847624, 8562.186592273723, 8749.212570868653, 4471.105778844244, 4480.171168333869, 4535.682502224812, 4635.605973492224, 4780.856574302777, 3441.0709108540555, 3465.393556127718, 3519.560054993126, 3552.9163605100175, 3556.928861422809, 539.2665974274983, 624.5409623926411, 634.6604566556962, 670.11210146556, 704.3801454719916, 620.0698951572641, 682.5733916302311, 774.1136398823428, 778.4823092643442, 783.4085265624417, 9973.60105595768, 10731.195160363004, 10788.892111078789, 10883.292585981886, 11013.229073964905, 654.0059110152637, 676.6143298319911, 733.7541923455595, 752.0824593995317, 790.9881351779728, 4778.402699662543, 5061.88401337926, 5100.6429549931145, 5122.769475373617, 5162.716050617177, 119406.83558986489, 119901.40049299698, 121422.0, 122336.0, 122479.0, 106348.99999999846, 106766.0, 106774.46612766887, 108093.4595327034, 108638.0, 90141.0, 92442.07557924469, 93498.0650193503, 94151.52924235472, 95769.52115239379, 9505.382150160218, 9508.524573771145, 9577.281703872099, 9625.278104142077, 9735.41587504752, 53057.46942530525, 54128.75024836207, 55040.0, 55042.449575505336, 56602.15096773634, 74617.2538274632, 77807.61096194483, 82334.0, 83838.0, 84637.0, 12290.446929888356, 12694.984401247693, 12912.418941147484, 13229.603111906657, 13370.465181392636, 16023.759643605123, 16063.277152528399, 16083.437079702155, 16667.83332166663, 16933.661326773643, 14351.425942962165, 14699.11805291686, 14705.705885882437, 15059.096454212782, 15855.356042076815, 14177.220252885778, 14423.49517766873, 14560.563183104523, 14679.41282348694, 14774.48289309912, 14265.786013209603, 14267.928660357047, 14468.782968255275, 14480.782788257973, 14644.19456929879, 10808.481552474927, 10823.0, 11242.213045086766, 11251.831222531833, 11672.824907626222, 12210.816837747263, 12213.511459541518, 12214.26714397139, 12405.379731013594, 12422.440990155645], "value": 7877.877599720081, "unit": "score"}], "name": "dromaeo_css", "value": 7302.438795616297, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 109.0, 110.0, 111.0, 111.0, 111.0, 109.0, 108.0, 109.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 120.0, 167.0, 121.0, 123.0, 144.0, 116.0, 144.0, 117.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 159.0, 162.0, 161.0, 160.0, 160.0, 162.0, 161.0, 164.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 121.0, 75.0, 76.0, 75.0, 75.0, 105.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 111.0, 83.0, 84.0, 84.0, 123.0, 84.0, 84.0, 127.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 50.0, 50.0, 51.0, 51.0, 50.0, 50.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 62.0, 62.0, 61.0, 61.0, 62.0, 63.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 75.0, 75.0, 75.0, 78.0, 78.0, 74.0, 75.0, 84.0], "value": 77.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 121.0, 119.0, 130.0, 118.0, 120.0, 120.0, 127.0, 171.0, 129.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 161.0, 160.0, 156.0, 155.0, 161.0, 155.0, 163.0, 153.0, 154.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 60.0, 62.0, 62.0, 63.0, 64.0, 65.0, 60.0], "value": 62.1, "unit": "score"}], "name": "kraken", "value": 1472.2, "alertThreshold": 2.0}]} 22:00:03 INFO - Return code: 0 22:00:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:00:03 INFO - # TBPL SUCCESS # 22:00:03 INFO - Running post-action listener: _resource_record_post_action 22:00:03 INFO - ##### 22:00:03 INFO - ##### Finished run-tests step (success) 22:00:03 INFO - ##### 22:00:03 INFO - Running post-run listener: _resource_record_post_run 22:00:04 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 558006272; Read time: 12; Write time: 486732 22:00:04 INFO - TinderboxPrint: CPU usage
12.9% 22:00:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:00:04 INFO - TinderboxPrint: I/O write bytes / time
558,006,272 / 486,732 22:00:04 INFO - TinderboxPrint: CPU idle
8,275.3 (87.0%) 22:00:04 INFO - TinderboxPrint: CPU user
1,187.7 (12.5%) 22:00:04 INFO - TinderboxPrint: Swap in / out
0 / 0 22:00:04 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 261025792; Read time: 0; Write time: 263748 22:00:04 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290320384; Read time: 12; Write time: 220740 22:00:04 INFO - Running post-run listener: _upload_blobber_files 22:00:04 INFO - Blob upload gear active. 22:00:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:00:04 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 22:00:04 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:00:04 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:00:05 INFO - (blobuploader) - INFO - Open directory for files ... 22:00:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:00:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:00:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:00:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:00:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:00:06 INFO - (blobuploader) - INFO - Done attempting. 22:00:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:00:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:00:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:00:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:00:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:00:17 INFO - (blobuploader) - INFO - Done attempting. 22:00:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:00:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:00:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:00:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:00:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:00:18 INFO - (blobuploader) - INFO - Done attempting. 22:00:18 INFO - (blobuploader) - INFO - Iteration through files over. 22:00:18 INFO - Return code: 0 22:00:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:00:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:00:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a35ca3cebf18b656eee546a89d735154ce192c071a55e3042381b56e034c7a8c3d1298601028dd14e4f58a5cd996e1d58fe24f62118aaba0ea4c882c287211f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c22fe0868dab3a2457c020822b8c59b6ae268e97abd51edfc4ba78ade52ffb562133efbe4e5154d708b0bd17d2b3c3f1408fe9c8ead0fc359bd455426ab7eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a981143ee132f02c8530a1b296af568731c1b4cd451a3ef57eda51064f771358df4b7244e6da1709a88440aa5a56699ef9d4e4e55533d5e67c6faab9927babb4"} 22:00:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:00:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:00:18 INFO - Contents: 22:00:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a35ca3cebf18b656eee546a89d735154ce192c071a55e3042381b56e034c7a8c3d1298601028dd14e4f58a5cd996e1d58fe24f62118aaba0ea4c882c287211f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c22fe0868dab3a2457c020822b8c59b6ae268e97abd51edfc4ba78ade52ffb562133efbe4e5154d708b0bd17d2b3c3f1408fe9c8ead0fc359bd455426ab7eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a981143ee132f02c8530a1b296af568731c1b4cd451a3ef57eda51064f771358df4b7244e6da1709a88440aa5a56699ef9d4e4e55533d5e67c6faab9927babb4"} 22:00:18 INFO - Running post-run listener: copy_logs_to_upload_dir 22:00:18 INFO - Copying logs to upload dir... 22:00:18 INFO - mkdir: /builds/slave/test/build/upload/logs 22:00:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.666474 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-18 22:00:17.629923) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 22:00:17.633832) ========= 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=836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a35ca3cebf18b656eee546a89d735154ce192c071a55e3042381b56e034c7a8c3d1298601028dd14e4f58a5cd996e1d58fe24f62118aaba0ea4c882c287211f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c22fe0868dab3a2457c020822b8c59b6ae268e97abd51edfc4ba78ade52ffb562133efbe4e5154d708b0bd17d2b3c3f1408fe9c8ead0fc359bd455426ab7eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a981143ee132f02c8530a1b296af568731c1b4cd451a3ef57eda51064f771358df4b7244e6da1709a88440aa5a56699ef9d4e4e55533d5e67c6faab9927babb4"} build_url:https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/TZsXsryWQjaUWglIXBpiAQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a35ca3cebf18b656eee546a89d735154ce192c071a55e3042381b56e034c7a8c3d1298601028dd14e4f58a5cd996e1d58fe24f62118aaba0ea4c882c287211f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5c22fe0868dab3a2457c020822b8c59b6ae268e97abd51edfc4ba78ade52ffb562133efbe4e5154d708b0bd17d2b3c3f1408fe9c8ead0fc359bd455426ab7eda", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a981143ee132f02c8530a1b296af568731c1b4cd451a3ef57eda51064f771358df4b7244e6da1709a88440aa5a56699ef9d4e4e55533d5e67c6faab9927babb4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 22:00:17.659668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 22:00:17.660006) ========= 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=836cfdc75598d54cb747368e000001a9-1468903177.801694-1683560665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 22:00:17.742381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 22:00:17.742750) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 22:00:17.743105) ========= ========= Total master_lag: 0.16 =========