builder: cedar_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-024 starttime: 1480436992.71 results: success (0) buildid: 20161129071027 builduid: f6b0a3d1e4c2404486ffb6ef0649a958 revision: 1ab6a9dfef56cedb1d917aac82240a3be0fb8f20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.712719) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.713578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.714028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.787919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.788382) ========= 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=41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-29 08:29:53-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.5M=0s 2016-11-29 08:29:53 (50.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149913 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.960415) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:52.960819) ========= 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=41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014258 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:53.016170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 08:29:53.016520) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 1ab6a9dfef56cedb1d917aac82240a3be0fb8f20 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 1ab6a9dfef56cedb1d917aac82240a3be0fb8f20 --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=41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-29 08:29:53,289 truncating revision to first 12 chars 2016-11-29 08:29:53,289 Setting DEBUG logging. 2016-11-29 08:29:53,289 attempt 1/10 2016-11-29 08:29:53,289 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/1ab6a9dfef56?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 08:29:54,173 unpacking tar archive at: cedar-1ab6a9dfef56/testing/mozharness/ program finished with exit code 0 elapsedTime=1.192397 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 08:29:54.224536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:54.224935) ========= script_repo_revision: 1ab6a9dfef56cedb1d917aac82240a3be0fb8f20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:54.225482) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:54.225906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 08:29:54.236155) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-11-29 08:29:54.236620) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] 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=41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:29:54 INFO - MultiFileLogger online at 20161129 08:29:54 in /builds/slave/test 08:29:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 08:29:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:29:54 INFO - {'append_to_log': False, 08:29:54 INFO - 'base_work_dir': '/builds/slave/test', 08:29:54 INFO - 'blob_upload_branch': 'Cedar', 08:29:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:29:54 INFO - 'branch': 'Cedar', 08:29:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:29:54 INFO - 'config_files': ('talos/linux_config.py',), 08:29:54 INFO - 'default_actions': ('clobber', 08:29:54 INFO - 'read-buildbot-config', 08:29:54 INFO - 'download-and-extract', 08:29:54 INFO - 'populate-webroot', 08:29:54 INFO - 'create-virtualenv', 08:29:54 INFO - 'install', 08:29:54 INFO - 'run-tests'), 08:29:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:29:54 INFO - 'download_minidump_stackwalk': True, 08:29:54 INFO - 'download_symbols': 'ondemand', 08:29:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:29:54 INFO - 'tooltool.py': '/tools/tooltool.py', 08:29:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:29:54 INFO - '/tools/misc-python/virtualenv.py')}, 08:29:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:29:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:29:54 INFO - 'installer_path': 'installer.exe', 08:29:54 INFO - 'log_level': 'info', 08:29:54 INFO - 'log_name': 'talos', 08:29:54 INFO - 'log_to_console': True, 08:29:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:29:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:29:54 INFO - 'opt_config_files': (), 08:29:54 INFO - 'pip_index': False, 08:29:54 INFO - 'sps_profile': False, 08:29:54 INFO - 'sps_profile_interval': 0, 08:29:54 INFO - 'suite': 'dromaeojs', 08:29:54 INFO - 'system_bits': '32', 08:29:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:29:54 INFO - 'title': 'talos-linux64-ix-024', 08:29:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:29:54 INFO - 'use_talos_json': True, 08:29:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:29:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:29:54 INFO - 'work_dir': 'build'} 08:29:54 INFO - [mozharness: 2016-11-29 16:29:54.614427Z] Running clobber step. 08:29:54 INFO - Running pre-action listener: _resource_record_pre_action 08:29:54 INFO - Running main action method: clobber 08:29:54 INFO - rmtree: /builds/slave/test/build 08:29:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:29:55 INFO - Running post-action listener: _resource_record_post_action 08:29:55 INFO - [mozharness: 2016-11-29 16:29:55.078476Z] Finished clobber step (success) 08:29:55 INFO - [mozharness: 2016-11-29 16:29:55.078576Z] Running read-buildbot-config step. 08:29:55 INFO - Running pre-action listener: _resource_record_pre_action 08:29:55 INFO - Running main action method: read_buildbot_config 08:29:55 INFO - Using buildbot properties: 08:29:55 INFO - { 08:29:55 INFO - "project": "", 08:29:55 INFO - "product": "firefox", 08:29:55 INFO - "script_repo_revision": "default", 08:29:55 INFO - "builddir": "cedar_ubuntu64_hw_test-dromaeojs", 08:29:55 INFO - "repository": "", 08:29:55 INFO - "buildername": "Ubuntu HW 12.04 x64 cedar talos dromaeojs", 08:29:55 INFO - "buildid": "20161129071027", 08:29:55 INFO - "pgo_build": "False", 08:29:55 INFO - "basedir": "/builds/slave/test", 08:29:55 INFO - "buildnumber": 3, 08:29:55 INFO - "slavename": "talos-linux64-ix-024", 08:29:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:29:55 INFO - "platform": "ubuntu64_hw", 08:29:55 INFO - "scheduler": "tests-cedar-linux64-talos", 08:29:55 INFO - "branch": "cedar", 08:29:55 INFO - "repo_path": "projects/cedar", 08:29:55 INFO - "revision": "1ab6a9dfef56cedb1d917aac82240a3be0fb8f20", 08:29:55 INFO - "stage_platform": "linux64", 08:29:55 INFO - "builduid": "f6b0a3d1e4c2404486ffb6ef0649a958", 08:29:55 INFO - "slavebuilddir": "test" 08:29:55 INFO - } 08:29:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 08:29:55 INFO - Running post-action listener: _resource_record_post_action 08:29:55 INFO - [mozharness: 2016-11-29 16:29:55.080998Z] Finished read-buildbot-config step (success) 08:29:55 INFO - [mozharness: 2016-11-29 16:29:55.081084Z] Running download-and-extract step. 08:29:55 INFO - Running pre-action listener: _resource_record_pre_action 08:29:55 INFO - Running main action method: download_and_extract 08:29:55 INFO - mkdir: /builds/slave/test/build/tests 08:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:55 INFO - trying https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:29:55 INFO - Downloading https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:29:55 INFO - Downloaded 1361 bytes. 08:29:55 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 08:29:55 INFO - Using the following test package requirements: 08:29:55 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:29:55 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:29:55 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:29:55 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'jsshell-linux-x86_64.zip'], 08:29:55 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:29:55 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 08:29:55 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:29:55 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:29:55 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:29:55 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 08:29:55 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:29:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:29:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:29:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:29:55 INFO - Fetch https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:29:56 INFO - Http code: 200 08:29:56 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 08:29:56 INFO - x-amz-cf-id: BC8A8KIlE3crm8q2vAFw2VvTmEoNMzM4J3brnVm1ymQjCkyWTFyLBg== 08:29:56 INFO - x-amz-version-id: pEdv.pDSQij.AyhcwGedZdH23WIXCXut 08:29:56 INFO - Expected file size: 20219690 08:29:56 INFO - Obtained file size: 20219690 08:30:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:30:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 08:30:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:30:00 INFO - Fetch https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:30:01 INFO - Http code: 200 08:30:01 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 08:30:01 INFO - x-amz-cf-id: QyBs34o9Ds8AnixeNM_y9Cu4Nc6Wn6XzY3mHqcCW95g0NyYbWWBAyQ== 08:30:01 INFO - x-amz-version-id: pEdv.pDSQij.AyhcwGedZdH23WIXCXut 08:30:01 INFO - Expected file size: 20219690 08:30:01 INFO - Obtained file size: 20219690 08:30:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 08:30:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:30:05 INFO - Fetch https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:30:06 INFO - Http code: 200 08:30:06 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 08:30:06 INFO - x-amz-cf-id: F6J3YEBK1FDgRvTk9j46EzeMPyWTQOn0fI0Xls80aSPALMp04PeKrA== 08:30:06 INFO - x-amz-version-id: E3GCIPwxlsDn73zG6AJZk0VMDGOs2U2N 08:30:06 INFO - Expected file size: 14409702 08:30:06 INFO - Obtained file size: 14409702 08:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:06 INFO - trying https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:06 INFO - Downloading https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:30:09 INFO - Downloaded 55698634 bytes. 08:30:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:09 INFO - mkdir: /builds/slave/test/properties 08:30:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:09 INFO - Contents: 08:30:09 INFO - build_url:https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 08:30:10 INFO - Running post-action listener: _resource_record_post_action 08:30:10 INFO - Running post-action listener: set_extra_try_arguments 08:30:10 INFO - [mozharness: 2016-11-29 16:30:10.092728Z] Finished download-and-extract step (success) 08:30:10 INFO - [mozharness: 2016-11-29 16:30:10.092960Z] Running populate-webroot step. 08:30:10 INFO - Running pre-action listener: _resource_record_pre_action 08:30:10 INFO - Running main action method: populate_webroot 08:30:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:10 INFO - '--tppagecycles', 08:30:10 INFO - '7'], 08:30:10 INFO - 'tests': ['tsvgm']}}, 08:30:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['damp', 'tps']}, 08:30:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'tests': ['damp', 'tps']}, 08:30:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['dromaeo_dom']}, 08:30:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['basic_compositor_video']}, 08:30:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['a11yr', 08:30:10 INFO - 'ts_paint', 08:30:10 INFO - 'tpaint', 08:30:10 INFO - 'sessionrestore', 08:30:10 INFO - 'sessionrestore_no_auto_restore', 08:30:10 INFO - 'tabpaint']}, 08:30:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:10 INFO - 'ts_paint', 08:30:10 INFO - 'tpaint', 08:30:10 INFO - 'sessionrestore', 08:30:10 INFO - 'sessionrestore_no_auto_restore', 08:30:10 INFO - 'tabpaint']}, 08:30:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:10 INFO - 'ts_paint', 08:30:10 INFO - 'tpaint', 08:30:10 INFO - 'sessionrestore', 08:30:10 INFO - 'sessionrestore_no_auto_restore', 08:30:10 INFO - 'tabpaint']}, 08:30:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:10 INFO - 'ts_paint', 08:30:10 INFO - 'tpaint', 08:30:10 INFO - 'sessionrestore', 08:30:10 INFO - 'sessionrestore_no_auto_restore', 08:30:10 INFO - 'tabpaint']}, 08:30:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['a11yr', 08:30:10 INFO - 'ts_paint', 08:30:10 INFO - 'tpaint', 08:30:10 INFO - 'sessionrestore', 08:30:10 INFO - 'sessionrestore_no_auto_restore', 08:30:10 INFO - 'tabpaint']}, 08:30:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['a11yr', 08:30:10 INFO - 'ts_paint', 08:30:10 INFO - 'tpaint', 08:30:10 INFO - 'sessionrestore', 08:30:10 INFO - 'sessionrestore_no_auto_restore', 08:30:10 INFO - 'tabpaint']}, 08:30:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['tsvgx', 08:30:10 INFO - 'tsvgr_opacity', 08:30:10 INFO - 'tart', 08:30:10 INFO - 'tscrollx', 08:30:10 INFO - 'cart', 08:30:10 INFO - 'tsvg_static']}, 08:30:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:10 INFO - 'tsvgr_opacity', 08:30:10 INFO - 'tart', 08:30:10 INFO - 'tscrollx', 08:30:10 INFO - 'cart', 08:30:10 INFO - 'tsvg_static']}, 08:30:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'talos_options': ['--disable-e10s'], 08:30:10 INFO - 'tests': ['tp5o']}, 08:30:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:30:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'talos_options': ['--disable-e10s', 08:30:10 INFO - '--xperf_path', 08:30:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:10 INFO - 'tests': ['tp5n']}, 08:30:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:30:10 INFO - 'talos_options': ['--xperf_path', 08:30:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:10 INFO - 'tests': ['tp5n']}}, 08:30:10 INFO - 'talos.zip': {'path': '', 08:30:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:10 INFO - Running post-action listener: _resource_record_post_action 08:30:10 INFO - [mozharness: 2016-11-29 16:30:10.111628Z] Finished populate-webroot step (success) 08:30:10 INFO - [mozharness: 2016-11-29 16:30:10.111715Z] Running create-virtualenv step. 08:30:10 INFO - Running pre-action listener: _resource_record_pre_action 08:30:10 INFO - Running main action method: create_virtualenv 08:30:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:10 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 08:30:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:10 INFO - Using real prefix '/usr' 08:30:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:12 INFO - Installing pip.................done. 08:30:12 INFO - Return code: 0 08:30:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:30:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:30:12 INFO - Reading from file tmpfile_stdout 08:30:12 INFO - Output received: 08:30:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:30:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:12 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:12 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 08:30:12 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 08:30:12 INFO - Using env: {'DISPLAY': ':0', 08:30:12 INFO - 'HOME': '/home/cltbld', 08:30:12 INFO - 'LANG': 'en_US.UTF-8', 08:30:12 INFO - 'LANGUAGE': 'en_US:en', 08:30:12 INFO - 'LOGNAME': 'cltbld', 08:30:12 INFO - 'MAIL': '/var/mail/cltbld', 08:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:12 INFO - 'MOZ_NO_REMOTE': '1', 08:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:12 INFO - 'NO_EM_RESTART': '1', 08:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:12 INFO - 'PWD': '/builds/slave/test', 08:30:12 INFO - 'SHELL': '/bin/bash', 08:30:12 INFO - 'SHLVL': '1', 08:30:12 INFO - 'TERM': 'linux', 08:30:12 INFO - 'TMOUT': '86400', 08:30:12 INFO - 'USER': 'cltbld', 08:30:12 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:12 INFO - '_': '/tools/buildbot/bin/python'} 08:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:13 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 08:30:13 INFO - Cleaning up... 08:30:13 INFO - Return code: 0 08:30:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:13 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:13 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 08:30:13 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 08:30:13 INFO - Using env: {'DISPLAY': ':0', 08:30:13 INFO - 'HOME': '/home/cltbld', 08:30:13 INFO - 'LANG': 'en_US.UTF-8', 08:30:13 INFO - 'LANGUAGE': 'en_US:en', 08:30:13 INFO - 'LOGNAME': 'cltbld', 08:30:13 INFO - 'MAIL': '/var/mail/cltbld', 08:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:13 INFO - 'MOZ_NO_REMOTE': '1', 08:30:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:13 INFO - 'NO_EM_RESTART': '1', 08:30:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:13 INFO - 'PWD': '/builds/slave/test', 08:30:13 INFO - 'SHELL': '/bin/bash', 08:30:13 INFO - 'SHLVL': '1', 08:30:13 INFO - 'TERM': 'linux', 08:30:13 INFO - 'TMOUT': '86400', 08:30:13 INFO - 'USER': 'cltbld', 08:30:13 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:13 INFO - '_': '/tools/buildbot/bin/python'} 08:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:13 INFO - Downloading/unpacking psutil>=3.1.1 08:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:15 INFO - Installing collected packages: psutil 08:30:15 INFO - Running setup.py install for psutil 08:30:15 INFO - building 'psutil._psutil_linux' extension 08:30:15 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 08:30:15 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 08:30:15 INFO - building 'psutil._psutil_posix' extension 08:30:15 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 08:30:15 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 08:30:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:15 INFO - Successfully installed psutil 08:30:15 INFO - Cleaning up... 08:30:15 INFO - Return code: 0 08:30:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:16 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:16 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 08:30:16 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 08:30:16 INFO - Using env: {'DISPLAY': ':0', 08:30:16 INFO - 'HOME': '/home/cltbld', 08:30:16 INFO - 'LANG': 'en_US.UTF-8', 08:30:16 INFO - 'LANGUAGE': 'en_US:en', 08:30:16 INFO - 'LOGNAME': 'cltbld', 08:30:16 INFO - 'MAIL': '/var/mail/cltbld', 08:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:16 INFO - 'MOZ_NO_REMOTE': '1', 08:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:16 INFO - 'NO_EM_RESTART': '1', 08:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:16 INFO - 'PWD': '/builds/slave/test', 08:30:16 INFO - 'SHELL': '/bin/bash', 08:30:16 INFO - 'SHLVL': '1', 08:30:16 INFO - 'TERM': 'linux', 08:30:16 INFO - 'TMOUT': '86400', 08:30:16 INFO - 'USER': 'cltbld', 08:30:16 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:16 INFO - '_': '/tools/buildbot/bin/python'} 08:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:16 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 08:30:16 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 08:30:16 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 08:30:16 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 08:30:16 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 08:30:16 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 08:30:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:18 INFO - Installing collected packages: mozsystemmonitor 08:30:18 INFO - Running setup.py install for mozsystemmonitor 08:30:18 INFO - Successfully installed mozsystemmonitor 08:30:18 INFO - Cleaning up... 08:30:18 INFO - Return code: 0 08:30:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:18 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:18 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 08:30:18 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 08:30:18 INFO - Using env: {'DISPLAY': ':0', 08:30:18 INFO - 'HOME': '/home/cltbld', 08:30:18 INFO - 'LANG': 'en_US.UTF-8', 08:30:18 INFO - 'LANGUAGE': 'en_US:en', 08:30:18 INFO - 'LOGNAME': 'cltbld', 08:30:18 INFO - 'MAIL': '/var/mail/cltbld', 08:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:18 INFO - 'MOZ_NO_REMOTE': '1', 08:30:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:18 INFO - 'NO_EM_RESTART': '1', 08:30:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:18 INFO - 'PWD': '/builds/slave/test', 08:30:18 INFO - 'SHELL': '/bin/bash', 08:30:18 INFO - 'SHLVL': '1', 08:30:18 INFO - 'TERM': 'linux', 08:30:18 INFO - 'TMOUT': '86400', 08:30:18 INFO - 'USER': 'cltbld', 08:30:18 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:18 INFO - '_': '/tools/buildbot/bin/python'} 08:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:18 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:18 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 08:30:18 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 08:30:18 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 08:30:18 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 08:30:18 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 08:30:18 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 08:30:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:20 INFO - warning: no files found matching '*.txt' 08:30:20 INFO - no previously-included directories found matching 'build' 08:30:20 INFO - no previously-included directories found matching 'dist' 08:30:20 INFO - no previously-included directories found matching '.git*' 08:30:20 INFO - Installing collected packages: jsonschema, functools32 08:30:20 INFO - Running setup.py install for functools32 08:30:20 INFO - warning: no files found matching '*.txt' 08:30:20 INFO - no previously-included directories found matching 'build' 08:30:20 INFO - no previously-included directories found matching 'dist' 08:30:20 INFO - no previously-included directories found matching '.git*' 08:30:20 INFO - Successfully installed jsonschema functools32 08:30:20 INFO - Cleaning up... 08:30:21 INFO - Return code: 0 08:30:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:30:21 INFO - Using env: {'DISPLAY': ':0', 08:30:21 INFO - 'HOME': '/home/cltbld', 08:30:21 INFO - 'LANG': 'en_US.UTF-8', 08:30:21 INFO - 'LANGUAGE': 'en_US:en', 08:30:21 INFO - 'LOGNAME': 'cltbld', 08:30:21 INFO - 'MAIL': '/var/mail/cltbld', 08:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:21 INFO - 'MOZ_NO_REMOTE': '1', 08:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:21 INFO - 'NO_EM_RESTART': '1', 08:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:21 INFO - 'PWD': '/builds/slave/test', 08:30:21 INFO - 'SHELL': '/bin/bash', 08:30:21 INFO - 'SHLVL': '1', 08:30:21 INFO - 'TERM': 'linux', 08:30:21 INFO - 'TMOUT': '86400', 08:30:21 INFO - 'USER': 'cltbld', 08:30:21 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:21 INFO - '_': '/tools/buildbot/bin/python'} 08:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:21 INFO - Cleaning up... 08:30:21 INFO - Return code: 0 08:30:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:21 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:21 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 08:30:21 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 08:30:21 INFO - Using env: {'DISPLAY': ':0', 08:30:21 INFO - 'HOME': '/home/cltbld', 08:30:21 INFO - 'LANG': 'en_US.UTF-8', 08:30:21 INFO - 'LANGUAGE': 'en_US:en', 08:30:21 INFO - 'LOGNAME': 'cltbld', 08:30:21 INFO - 'MAIL': '/var/mail/cltbld', 08:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:21 INFO - 'MOZ_NO_REMOTE': '1', 08:30:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:21 INFO - 'NO_EM_RESTART': '1', 08:30:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:21 INFO - 'PWD': '/builds/slave/test', 08:30:21 INFO - 'SHELL': '/bin/bash', 08:30:21 INFO - 'SHLVL': '1', 08:30:21 INFO - 'TERM': 'linux', 08:30:21 INFO - 'TMOUT': '86400', 08:30:21 INFO - 'USER': 'cltbld', 08:30:21 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:21 INFO - '_': '/tools/buildbot/bin/python'} 08:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:21 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 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 08:30:23 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:30:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:24 INFO - Downloading docopt-0.6.1.tar.gz 08:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:24 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:24 INFO - Running setup.py install for blobuploader 08:30:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:30:24 INFO - Running setup.py install for requests 08:30:24 INFO - Running setup.py install for docopt 08:30:24 INFO - Successfully installed blobuploader requests docopt 08:30:24 INFO - Cleaning up... 08:30:24 INFO - Return code: 0 08:30:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:24 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:24 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 08:30:24 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 08:30:24 INFO - Using env: {'DISPLAY': ':0', 08:30:24 INFO - 'HOME': '/home/cltbld', 08:30:24 INFO - 'LANG': 'en_US.UTF-8', 08:30:24 INFO - 'LANGUAGE': 'en_US:en', 08:30:24 INFO - 'LOGNAME': 'cltbld', 08:30:24 INFO - 'MAIL': '/var/mail/cltbld', 08:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:24 INFO - 'MOZ_NO_REMOTE': '1', 08:30:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:24 INFO - 'NO_EM_RESTART': '1', 08:30:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:24 INFO - 'PWD': '/builds/slave/test', 08:30:24 INFO - 'SHELL': '/bin/bash', 08:30:24 INFO - 'SHLVL': '1', 08:30:24 INFO - 'TERM': 'linux', 08:30:24 INFO - 'TMOUT': '86400', 08:30:24 INFO - 'USER': 'cltbld', 08:30:24 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:24 INFO - '_': '/tools/buildbot/bin/python'} 08:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:25 INFO - Running setup.py (path:/tmp/pip-YWLC05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:25 INFO - Running setup.py (path:/tmp/pip-QjoPaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:25 INFO - Running setup.py (path:/tmp/pip-_vsLIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:25 INFO - Running setup.py (path:/tmp/pip-q_4lu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:25 INFO - Running setup.py (path:/tmp/pip-AzcEpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:25 INFO - Running setup.py (path:/tmp/pip-FQgX95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:25 INFO - Running setup.py (path:/tmp/pip-3HlxuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:25 INFO - Running setup.py (path:/tmp/pip-S7soZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:25 INFO - Running setup.py (path:/tmp/pip-pxbU67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:25 INFO - Running setup.py (path:/tmp/pip-p6Ibn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:25 INFO - Running setup.py (path:/tmp/pip-Lwv2D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:25 INFO - Running setup.py (path:/tmp/pip-abfnqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:26 INFO - Running setup.py (path:/tmp/pip-z_GgKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:26 INFO - Running setup.py (path:/tmp/pip-xOovHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:26 INFO - Running setup.py (path:/tmp/pip-FwZCGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:26 INFO - Running setup.py (path:/tmp/pip-eyF_kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:26 INFO - Running setup.py (path:/tmp/pip-lkHUJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:30:26 INFO - Running setup.py install for manifestparser 08:30:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:30:26 INFO - Running setup.py install for mozcrash 08:30:26 INFO - Running setup.py install for mozdebug 08:30:26 INFO - Running setup.py install for mozdevice 08:30:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:30:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:30:26 INFO - Running setup.py install for mozfile 08:30:26 INFO - Running setup.py install for mozhttpd 08:30:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:30:26 INFO - Running setup.py install for mozinfo 08:30:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Running setup.py install for mozInstall 08:30:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Running setup.py install for mozleak 08:30:27 INFO - Running setup.py install for mozlog 08:30:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Running setup.py install for moznetwork 08:30:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Running setup.py install for mozprocess 08:30:27 INFO - Running setup.py install for mozprofile 08:30:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Running setup.py install for mozrunner 08:30:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:30:27 INFO - Running setup.py install for mozscreenshot 08:30:27 INFO - Running setup.py install for moztest 08:30:28 INFO - Running setup.py install for mozversion 08:30:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:30:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:30:28 INFO - Cleaning up... 08:30:28 INFO - Return code: 0 08:30:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:28 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:28 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 08:30:28 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 08:30:28 INFO - Using env: {'DISPLAY': ':0', 08:30:28 INFO - 'HOME': '/home/cltbld', 08:30:28 INFO - 'LANG': 'en_US.UTF-8', 08:30:28 INFO - 'LANGUAGE': 'en_US:en', 08:30:28 INFO - 'LOGNAME': 'cltbld', 08:30:28 INFO - 'MAIL': '/var/mail/cltbld', 08:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:28 INFO - 'MOZ_NO_REMOTE': '1', 08:30:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:28 INFO - 'NO_EM_RESTART': '1', 08:30:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:28 INFO - 'PWD': '/builds/slave/test', 08:30:28 INFO - 'SHELL': '/bin/bash', 08:30:28 INFO - 'SHLVL': '1', 08:30:28 INFO - 'TERM': 'linux', 08:30:28 INFO - 'TMOUT': '86400', 08:30:28 INFO - 'USER': 'cltbld', 08:30:28 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:28 INFO - '_': '/tools/buildbot/bin/python'} 08:30:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:28 INFO - Running setup.py (path:/tmp/pip-JUZvMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:28 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:28 INFO - Running setup.py (path:/tmp/pip-etdx25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:28 INFO - Running setup.py (path:/tmp/pip-0gzuiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:28 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:28 INFO - Running setup.py (path:/tmp/pip-py_xz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:28 INFO - Running setup.py (path:/tmp/pip-3kd9ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:28 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:28 INFO - Running setup.py (path:/tmp/pip-vsfMWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:28 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:28 INFO - Running setup.py (path:/tmp/pip-w4exHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:28 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:28 INFO - Running setup.py (path:/tmp/pip-IGeysn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:28 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)) 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:28 INFO - Running setup.py (path:/tmp/pip-Grwqug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:29 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:29 INFO - Running setup.py (path:/tmp/pip-junZVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:29 INFO - Running setup.py (path:/tmp/pip-eq8N0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:29 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:29 INFO - Running setup.py (path:/tmp/pip-wiwk5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:29 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:29 INFO - Running setup.py (path:/tmp/pip-cw4fJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:29 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:29 INFO - Running setup.py (path:/tmp/pip-6ADumk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:29 INFO - Running setup.py (path:/tmp/pip-KTuALl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:29 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:29 INFO - Running setup.py (path:/tmp/pip-_Bj8dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:29 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)) 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:29 INFO - Running setup.py (path:/tmp/pip-Xtd3mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:29 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)) 08:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:30:29 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)) 08:30:29 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:30:29 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:30:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:31 INFO - Downloading blessings-1.6.tar.gz 08:30:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:30:31 INFO - Installing collected packages: blessings 08:30:31 INFO - Running setup.py install for blessings 08:30:31 INFO - Successfully installed blessings 08:30:31 INFO - Cleaning up... 08:30:31 INFO - Return code: 0 08:30:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:30:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:32 INFO - Reading from file tmpfile_stdout 08:30:32 INFO - Current package versions: 08:30:32 INFO - argparse == 1.2.1 08:30:32 INFO - blessings == 1.6 08:30:32 INFO - blobuploader == 1.2.4 08:30:32 INFO - docopt == 0.6.1 08:30:32 INFO - functools32 == 3.2.3-2 08:30:32 INFO - jsonschema == 2.5.1 08:30:32 INFO - manifestparser == 1.1 08:30:32 INFO - mozInstall == 1.12 08:30:32 INFO - mozcrash == 1.0 08:30:32 INFO - mozdebug == 0.1 08:30:32 INFO - mozdevice == 0.49 08:30:32 INFO - mozfile == 1.2 08:30:32 INFO - mozhttpd == 0.7 08:30:32 INFO - mozinfo == 0.9 08:30:32 INFO - mozleak == 0.1 08:30:32 INFO - mozlog == 3.4 08:30:32 INFO - moznetwork == 0.27 08:30:32 INFO - mozprocess == 0.23 08:30:32 INFO - mozprofile == 0.28 08:30:32 INFO - mozrunner == 6.13 08:30:32 INFO - mozscreenshot == 0.1 08:30:32 INFO - mozsystemmonitor == 0.3 08:30:32 INFO - moztest == 0.7 08:30:32 INFO - mozversion == 1.4 08:30:32 INFO - psutil == 3.1.1 08:30:32 INFO - requests == 1.2.3 08:30:32 INFO - wsgiref == 0.1.2 08:30:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 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 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:32 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 08:30:32 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 08:30:32 INFO - Using env: {'DISPLAY': ':0', 08:30:32 INFO - 'HOME': '/home/cltbld', 08:30:32 INFO - 'LANG': 'en_US.UTF-8', 08:30:32 INFO - 'LANGUAGE': 'en_US:en', 08:30:32 INFO - 'LOGNAME': 'cltbld', 08:30:32 INFO - 'MAIL': '/var/mail/cltbld', 08:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:32 INFO - 'MOZ_NO_REMOTE': '1', 08:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:32 INFO - 'NO_EM_RESTART': '1', 08:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:32 INFO - 'PWD': '/builds/slave/test', 08:30:32 INFO - 'SHELL': '/bin/bash', 08:30:32 INFO - 'SHLVL': '1', 08:30:32 INFO - 'TERM': 'linux', 08:30:32 INFO - 'TMOUT': '86400', 08:30:32 INFO - 'USER': 'cltbld', 08:30:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:32 INFO - '_': '/tools/buildbot/bin/python'} 08:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 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)) 08:30:32 INFO - Cleaning up... 08:30:32 INFO - Return code: 0 08:30:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fafdcb5ae30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c2b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285e130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, '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': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', '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 08:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:30:32 INFO - Using env: {'DISPLAY': ':0', 08:30:32 INFO - 'HOME': '/home/cltbld', 08:30:32 INFO - 'LANG': 'en_US.UTF-8', 08:30:32 INFO - 'LANGUAGE': 'en_US:en', 08:30:32 INFO - 'LOGNAME': 'cltbld', 08:30:32 INFO - 'MAIL': '/var/mail/cltbld', 08:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:32 INFO - 'MOZ_NO_REMOTE': '1', 08:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:32 INFO - 'NO_EM_RESTART': '1', 08:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:32 INFO - 'PWD': '/builds/slave/test', 08:30:32 INFO - 'SHELL': '/bin/bash', 08:30:32 INFO - 'SHLVL': '1', 08:30:32 INFO - 'TERM': 'linux', 08:30:32 INFO - 'TMOUT': '86400', 08:30:32 INFO - 'USER': 'cltbld', 08:30:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:32 INFO - '_': '/tools/buildbot/bin/python'} 08:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:30:32 INFO - Cleaning up... 08:30:32 INFO - Return code: 0 08:30:32 INFO - Running post-action listener: _resource_record_post_action 08:30:32 INFO - Running post-action listener: _start_resource_monitoring 08:30:32 INFO - Starting resource monitoring. 08:30:32 INFO - [mozharness: 2016-11-29 16:30:32.594394Z] Finished create-virtualenv step (success) 08:30:32 INFO - [mozharness: 2016-11-29 16:30:32.594863Z] Running install step. 08:30:32 INFO - Running pre-action listener: _resource_record_pre_action 08:30:32 INFO - Running main action method: install 08:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:32 INFO - Reading from file tmpfile_stdout 08:30:32 INFO - Detecting whether we're running mozinstall >=1.0... 08:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:30:32 INFO - Reading from file tmpfile_stdout 08:30:32 INFO - Output received: 08:30:32 INFO - Usage: mozinstall [options] installer 08:30:32 INFO - Options: 08:30:32 INFO - -h, --help show this help message and exit 08:30:32 INFO - -d DEST, --destination=DEST 08:30:32 INFO - Directory to install application into. [default: 08:30:32 INFO - "/builds/slave/test"] 08:30:32 INFO - --app=APP Application being installed. [default: firefox] 08:30:32 INFO - mkdir: /builds/slave/test/build/application 08:30:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:30:48 INFO - Reading from file tmpfile_stdout 08:30:48 INFO - Output received: 08:30:48 INFO - /builds/slave/test/build/application/firefox/firefox 08:30:48 INFO - Running post-action listener: _resource_record_post_action 08:30:48 INFO - [mozharness: 2016-11-29 16:30:48.944644Z] Finished install step (success) 08:30:48 INFO - [mozharness: 2016-11-29 16:30:48.944950Z] Running run-tests step. 08:30:48 INFO - Running pre-action listener: _resource_record_pre_action 08:30:48 INFO - Running main action method: run_tests 08:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:30:48 INFO - Python 2.7.3 08:30:48 INFO - Return code: 0 08:30:48 INFO - grabbing minidump binary from tooltool 08:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:48 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 0x28ecfc0>, '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 0x28edec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28ee350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:30:48 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 08:30:48 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 08:30:48 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 08:30:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:30:49 INFO - Return code: 0 08:30:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:30:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:30:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:30:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:30:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 08:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 08:30:49 INFO - Using env: {'DISPLAY': ':0', 08:30:49 INFO - 'HOME': '/home/cltbld', 08:30:49 INFO - 'LANG': 'en_US.UTF-8', 08:30:49 INFO - 'LANGUAGE': 'en_US:en', 08:30:49 INFO - 'LOGNAME': 'cltbld', 08:30:49 INFO - 'MAIL': '/var/mail/cltbld', 08:30:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:30:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:49 INFO - 'MOZ_NO_REMOTE': '1', 08:30:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:49 INFO - 'NO_EM_RESTART': '1', 08:30:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:49 INFO - 'PWD': '/builds/slave/test', 08:30:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:30:49 INFO - 'SHELL': '/bin/bash', 08:30:49 INFO - 'SHLVL': '1', 08:30:49 INFO - 'TERM': 'linux', 08:30:49 INFO - 'TMOUT': '86400', 08:30:49 INFO - 'USER': 'cltbld', 08:30:49 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384', 08:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:49 INFO - '_': '/tools/buildbot/bin/python'} 08:30:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 08:30:49 INFO - mozversion application_buildid: 20161129071027 08:30:49 INFO - mozversion application_changeset: 1ab6a9dfef56cedb1d917aac82240a3be0fb8f20 08:30:49 INFO - mozversion application_display_name: Nightly 08:30:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:30:49 INFO - mozversion application_name: Firefox 08:30:49 INFO - mozversion application_remotingname: firefox 08:30:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 08:30:49 INFO - mozversion application_vendor: Mozilla 08:30:49 INFO - mozversion application_version: 53.0a1 08:30:49 INFO - mozversion platform_buildid: 20161129071027 08:30:49 INFO - mozversion platform_changeset: 1ab6a9dfef56cedb1d917aac82240a3be0fb8f20 08:30:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 08:30:49 INFO - mozversion platform_version: 53.0a1 08:30:49 INFO - using testdate: 1480437049 08:30:49 INFO - actual date: 1480437049 08:30:49 INFO - starting webserver on 'localhost:51911' 08:30:49 INFO - SUITE-START | Running 2 tests 08:30:49 INFO - TEST-START | dromaeo_css 08:30:49 INFO - operating with platform_type : linux_ 08:30:49 INFO - Initialising browser for dromaeo_css test... 08:30:49 INFO - TEST-INFO | started process 1252 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF0SerO/profile http://localhost:51911/getInfo.html) 08:30:53 INFO - PROCESS | 1252 | __metrics Screen width/height:1600/1200 08:30:53 INFO - PROCESS | 1252 | colorDepth:24 08:30:53 INFO - PROCESS | 1252 | Browser inner width/height: 1024/697 08:30:53 INFO - PROCESS | 1252 | __metrics 08:30:54 INFO - PROCESS | 1252 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:30:54 INFO - PROCESS | 1252 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:54 INFO - PROCESS | 1252 | console.error: PushService: 08:30:54 INFO - PROCESS | 1252 | stateChangeProcessEnqueue: Error transitioning state 08:30:54 INFO - PROCESS | 1252 | UnknownError 08:30:55 INFO - TEST-INFO | 1252: exit 0 08:30:55 INFO - Browser initialized. 08:30:55 INFO - Running cycle 1/1 for dromaeo_css test... 08:30:55 INFO - TEST-INFO | started process 1336 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF0SerO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:08 INFO - PROCESS | 1336 | RSS: Main: 174915584 08:31:08 INFO - PROCESS | 1336 | 08:32:00 INFO - PROCESS | 1336 | 1480437119995 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] 08:34:13 INFO - PROCESS | 1336 | Cycle 1(1): loaded http://localhost:51911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51911/tests/dromaeo/cssquery-ext.html) 08:34:14 INFO - PROCESS | 1336 | RSS: Main: 296927232 08:34:14 INFO - PROCESS | 1336 | 08:37:17 INFO - PROCESS | 1336 | Cycle 1(1): loaded http://localhost:51911/tests/dromaeo/cssquery-ext.html (next: http://localhost:51911/tests/dromaeo/cssquery-jquery.html) 08:37:18 INFO - PROCESS | 1336 | RSS: Main: 279343104 08:37:18 INFO - PROCESS | 1336 | 08:40:20 INFO - PROCESS | 1336 | Cycle 1(1): loaded http://localhost:51911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51911/tests/dromaeo/cssquery-mootools.html) 08:40:21 INFO - PROCESS | 1336 | RSS: Main: 218742784 08:40:21 INFO - PROCESS | 1336 | 08:43:23 INFO - PROCESS | 1336 | Cycle 1(1): loaded http://localhost:51911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51911/tests/dromaeo/cssquery-prototype.html) 08:43:24 INFO - PROCESS | 1336 | RSS: Main: 221650944 08:43:24 INFO - PROCESS | 1336 | 08:46:27 INFO - PROCESS | 1336 | Cycle 1(1): loaded http://localhost:51911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51911/tests/dromaeo/cssquery-yui.html) 08:46:27 INFO - PROCESS | 1336 | RSS: Main: 219316224 08:46:27 INFO - PROCESS | 1336 | 08:49:30 INFO - PROCESS | 1336 | Cycle 1(1): loaded http://localhost:51911/tests/dromaeo/cssquery-yui.html (next: http://localhost:51911/tests/dromaeo/cssquery-dojo.html) 08:49:31 INFO - PROCESS | 1336 | RSS: Main: 313552896 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | __start_tp_report 08:49:31 INFO - PROCESS | 1336 | _x_x_mozilla_page_load 08:49:31 INFO - PROCESS | 1336 | _x_x_mozilla_page_load_details 08:49:31 INFO - PROCESS | 1336 | |i|pagename|runs| 08:49:31 INFO - PROCESS | 1336 | |0;dojo.html;2567.9614805777915;2601.3756698392367;2615.411532405209;2630.6054091886213;2638.63059171716;4632.980744236271;4657.06858628274;4703.412073490814;4725.149473094842;4734.076855013273;4711.151992641323;4714.740689262089;4746.0507898420365;4775.641826862981;4805.135075686375;4222.303319952204;4224.408582798395;4267.317229243322;4291.699581029329;4389.47326320842;3995.956748335404;4294.012377153256;4361.6292615127595;4405.537418571052;4440.289553671413;4219.873403797891;4258.446401967755;4384.320430333303;4433.667474939392;4455.198064348416;4260.403543503912;4282.100758840647;4294.184105020067;4298.118885628439;4361.389405483234;4287.8070486828165;4292.506361768405;4296.162248361571;4300.1399720056115;4341.696081274312;4663.253879379283;4692.554207350297;4707.9877826267175;4708.576228139484;4720.43354797423;4898.804047838118;4945.851624451237;4947.628927830391;5005.0990821652085;5043.773030213612;5050.065737838511;5101.056304583627;5102.821401250939;5135.897282054338;5139.614528910347;4730.692504987199;4732.242841145469;4736.5973892219545;4744.69159504638;4787.162246606899;5031.22016087507;5076.720780357042;5202.947970520247;5209.843704688866;5257.5005374489365;4878.707277563358;4893.168161412568;4899.681520701143;4916.483769204182;4970.329005584272;4994.750262486934;4997.550220480174;5000.349954505891;5004.324416203839;5050.065737838511;4891.63312751538;4895.804167833318;4929.605631549467;4947.579455746302;4992.425871024879;4788.688735232199;4855.95144048555;4856.150173719585;4907;4908.214685650279;4215.59951804578;4221.7255878367805;4222.59885310895;4222.936655950163;4243.236217480883;3738.1776009277914;3756.511653485084;3757.3988161893967;3757.868474603376;3759.0602349412648;4241.63946064588;4265.488141422987;4273.722208056466;4276.35854621803;4279.957200428018;4967.627427942846;5008.949910500848;5016.1723315653235;5023.196288593807;5025.597952163818;4258.1696569169335;4292.420523229386;4307.461567304087;4309.633681137138;4310.52584215736;4502.414686090788;4523.660725445605;4525.524819893863;4527.796249168729;4561.064981678748;4686.64850136248;4690.061987602493;4692.272697731857;4695.319178718998;4741.431028276629;4947.035328110984;4981.800727970841;4986.052649996489;5026.824061157842;5050.747462626928;4719.882002949954;4721.433427988663;4722.1028004679;4722.929156062593;4723.622110231105;4897.9020419592125;4914.975425122851;4975.328330675457;4977.9751101244265;4978.775955081957;4989.376327959005;4993.15116430215;4999.775055111521;5014.34813474246;5037.823676171316;4862;4864;4870.391201099863;4878.414590249051;4919.1883339248625;4782.832600858953;4792.448868380042;4802.039592081528;4870.537298956593;4919.704817710949;4787.928181077356;4830.058138662927;4837.443693975238;4914.606831453403;4924;4988.201887697951;4991.101601711582;4993.525615066563;4998.125328067501;5015.899682006412;4880.341153944314;4883.291922671122;4886.291487734187;4886.364772579542;4940.061388336205 08:49:31 INFO - PROCESS | 1336 | |1;ext.html;539.4686234059452;548.8079172289697;552.5551930695789;565.7482420322958;571.1575426246287;26738.866305668467;36471;37687.24625507488;37742.43386349207;40103;37620.43569346462;37989.810050949774;38114.237715245676;38143.23713525735;38153.23693526128;7444.553326800396;7632.198619144993;7634.5037572557885;7634.694612215505;7635.618219089051;7445.4415918806035;7649.847003059936;9564.473953932531;9851.655192068243;9856.901430985707;9840.606375744961;9882.061204185591;9900.653477128302;9910.55402506889;9928.503574821267;9844.606215751397;9899.505024748734;9902.801943961153;9906.108450239477;9922.40465572068;9728.90271097287;9773.853392199122;9773.902260977371;9781.755456113582;9801.215902727836;35671.64328356709;36105.81947090274;36125;36160.457593136125;36176.63823361785;2997.2357048952595;3048.1465189746687;3108.9067327980197;3285.802851828898;3340.331933613287;27486.587701184497;27715.307117322027;27723.168304950883;28465.57301640477;28498.430031399257;3430.5540279763472;3448.448248280288;3499.510068590374;3525.0306165804354;3538.2569660371355;242479;243171;243254;243682.99999999822;244198;42634.78682606567;42695.786521067195;42738;42790.57209427866;42790.786046069574;50779.49220507822;50829.74585127125;50838;50941;51021.48978510167;12558.246505209716;12791.744165116646;12793.744125117446;12859.16415432994;12873.549425770054;8798.164174403422;9032.593533291018;9034.954825225832;9047.411918225294;9047.411918225425;12412.62762117138;14059.648508787363;14372.137671739729;14416.351264193136;14862;5567.749451274703;5605.327360716658;5635.182898479779;5638.499128967703;5646.887062258732;780.8711562592109;965.6041023180462;968.3270127261626;971.3152227679469;1001.1040119093516;26714.332141696614;27012.729872701413;27014.459710806066;27305.180844574694;27349.179524614294;906.2885634776669;930.7161315798746;954.1651055326589;998.2962011781676;1062.4156713807374;14346.928265358607;14378.35297411619;14488.782668259984;14574.344154513072;14670.853291467161;340024;341734;341832;342435;388955;41801;46477.535224647996;47509.28736068962;47542;51347.22979155316;1130.4630300607146;1141.8515592973026;1148.9023433008122;1166.877477864829;1172.1560476456937;26507.469850603266;27672.132458406937;27704.86147569249;28191.71808281891;28427.715722842506;35886.10284742798;36115.638843611225;36145;36146.27707445784;36158.638413615525;310938;312425;312465;312652;313643;33476.83261583676;34104.829475852464;34319.656803431644;34384.82807585946;34386.56134386541;39318.60681393264;40556.1888762229;40603.1879362417;40665;40665.796671016455;33683;34376.312473750884;34478.65521344855;34599.82700086483;34793.304133916674;32588.674113258563;33105;33108.66891331056;33772.493412599324;34130.317393651494;33368.49947250842;34047;34254;34442.48336275008;34617;26812.32969175708;26930.73069269282;30419.39161216719;30640.540391893694;31626.36747265088;24459.143929962367;24588.50822983566;24658.50682986366;24862;24881.62677559802 08:49:31 INFO - PROCESS | 1336 | |2;jquery.html;326.1211036257288;337.7061956098195;346.96356882527334;350.6948954409663;362.802272761345;12232;12275.877241227585;13291.335433228336;13312.667183320398;16083.035017898934;15200.16399098049;15370.923145384257;15419.15194664293;15525.990810597303;15617.375304987787;4693.460252071013;4709.411323584552;4771.761411929389;4888.828890988816;4895.36360273164;3517.155882588186;4755.45312289086;4839.5644392004715;4840.298156767267;4844.830430934918;3667.2665466906724;5470.835874923759;5753.108268218432;5758.078707406816;5841.357450680422;5626.915596266059;5757.050086735683;5842.591018628697;5849.327327357366;5850.122481627769;5079.390473143209;5081.974590127063;5088.313077734496;5092.210707340369;5094.490550944913;19102.140403681868;19600.313989010316;19777.802221977883;19850.20599176031;19924.501887452785;1880.6332765110672;1889.3104017033684;1921.28912302449;1926.489480287725;2180.2805074325433;11044.558217671363;11678.416079196091;12474.43865026076;12586.685332866751;12623.93688031554;1680.5546530169515;1688.4765722626025;1708.982910170895;1714.4942242038503;1730.4635562975518;63652;65799.3420065798;66678;67732.661336693;68201.65899170522;9388.342816002814;9460.574274157541;9503.857442138373;9537.2370210383;9549.75580738526;12517.311547864772;12594.874051259554;12621.179623324455;12627.747445051047;12632.936835315764;5255.185446255837;5274.97362513185;5276.947230527646;5316.548093412026;5413.377461591967;6144.2319710036245;6204.13142160098;6377.840553986095;6384.904226436607;6396.392342727433;3567.5897271813556;3787.2046870157023;3985.601439855991;4039.434479172918;4211.831526738896;1594.5614955886992;1653.297348626853;1691.6954948109212;1707.6840784454666;1727.9308827646753;830.5515021888112;879.3404946290283;880.4101252161067;896.8071864549023;933.0855761353776;15475.535733928;15914.92042539803;15993.200339983186;16178.984514800215;16720.41478548245;825.2613910550024;830.9667613295521;834.6953362022939;857.6312185760183;890.5413711938357;8734.781630459162;8848.1151884811;9258.490783006864;9303.906960930304;9363.110504502061;261220;332740;333665;333995;334235;80073;81872.18127818646;82219.5889020563;83857.16142838616;83893.58053209695;919.0763282900642;923.0308176414737;937.231470194434;958.9328746987784;965.0735294117726;15049.322780475124;15091.924540377227;15654.608634784221;15944.840551594336;15979.600509987344;36838.579370475716;37059.444108337855;37076.81461592675;37458.06354841042;38940.221195576494;49322;50933;50982.74508627433;51288.48711512837;51400.74299628478;6145.477634401126;6552.27924928267;6756.493263005196;6919.965400172967;7224.422046236394;8649.481031138152;11625;11752.823707644564;11898.881011189776;12261.141720079866;10155.543000564845;10302;10371.377717336984;10443.68668939933;10569.841452378067;9993.200543956611;10045.69862904114;10097.949510252402;10297.07326340603;10510.421926794099;10460.215483838894;10510.842337364793;10572.8942710575;10717.571297148026;10850.620228291971;6527.934720652923;6541.803745887631;6711.966440167768;6724.394804467622;6998.300169982961;8141.552214628252;9874.358166719428;10079.496025198858;10221.28451008452;10522.475700257426 08:49:31 INFO - PROCESS | 1336 | |3;mootools.html;1273.3378643105586;1309.8428188617365;1330.4079684540384;1569.285974881429;1587.0398408962578;6089.14751934729;6467.482601391889;6468.902966455507;6472.4174824265865;6476.935230647692;5658.122990936401;5869.618474799135;6078.300995385532;6289.402506761861;6293.5279854010905;1933.0624647046172;1956.0610906764762;1957.9510512237166;1959.804019598036;1970.123295133666;1910.436421255726;1945.221911235503;1960.5980773941308;1967.478618166187;1976.7825539190678;1681.3274690123988;1743.2591148761726;1906.6186762647526;1978.0307649251836;1980.2969945669297;1906.6853969095082;1914.0716752375088;1914.3778272061636;1920.0783623860555;1923.7210604462275;1942.9319973800991;1983.1175127068461;1984.087319832864;1985.434151266882;1988.0556735550642;6302;6322.683865806691;6341.1756471658855;6444.194475690539;6517.3808488193545;3730.570984336809;3835.0987517933263;3837.654611085016;3843.500344955166;3846.769193848378;2513.423740665814;2554.3358726731;2582.57387531059;2614.35948192694;2618.4370360372423;776.97669069928;807.9515229086273;810.1533897077509;821.7699044267555;825.4387016828554;8266.586670666442;8344.749657510285;8397.664093436317;8402.705905293285;8411.663533458654;3426.4860270959057;3482.59950105736;3549.8580056797955;3553.5202747629255;3560.9109772255383;7413.221611730691;7414.073240844895;7417.554946703215;7417.925820741723;7418.109826820816;3920.882373528799;3922.8626998054933;3923.843046278174;3925.371940489508;3926.312895243321;3604.044928094057;3606.2787442511603;3613.2231570212007;3613.2954073955325;3618.0412190769466;3019.8326309692484;3080.245339891741;3169.3661267746083;3173.2701478660038;3178.4437723398314;1048.3709774135457;1052.5158427123608;1054.5149231353664;1058.2486434631344;1068.738159151013;759.6999185321766;783.3380793229712;783.4046124945082;796.7012370361115;813.943023988327;4410.470743510799;4499.370088187657;4513.638908887281;4531.478879928851;4546.931796023062;723.2947875821033;749.966251518672;766.6818270417714;767.0764041925511;822.5352675738188;2993.5659329397117;3061.341811510536;3092.5206592978125;3111.0822307419367;3147.638021627542;7029.964850175818;7289.562626242442;7320.084989376328;7557.093148822067;7560.810979725551;7497.250274972459;7503.512271702322;7511.06111736033;7513.586752728543;7513.737019204302;5934.525237980951;6077.75688972445;6087;6087.817365479043;6089.756409743561;4329.891752706208;4407.9118417630825;4432.202203603382;4552.635789136927;4786.234202527578;6813.89779153323;6952.409045231212;7043.295670432915;7056.435485161277;7107.609081500567;6958.478114141359;7225.819354516179;7232.168300645493;7240.275972402717;7245.818854528679;5494.09347457665;5574.303212098488;5583.2462617547735;5632.295963004624;5641.887162256649;5879.323877754113;5916.171735956884;5933.1396947443245;5940.79207227745;5949.613275137102;5642.351129619982;5692.146178073156;5708.143778433102;5779.739911704068;5805.680687562224;5427.185922111715;5518.503334699896;5541.3904470509015;5548.417416171405;5552.278203833475;5320.920186197132;5323.4676532348;5331.680099194061;5383.730813459233;5395.730213489232;3798.905027374338;3833.0992216828467;3870.53553573573;3872.090058836115;3921.1961547882047;3890.2608504384075;3924.6664033557468;3985.103351745834;4002.9199416012098;4174.540800511881 08:49:31 INFO - PROCESS | 1336 | |4;prototype.html;265.4703865787754;266.74392583120215;267.9115891755721;268.9166358428887;275.21426327834047;4880.536349046839;4890.679516530539;4903.8230824602015;4919.237518184678;4936.851894443168;4823.855284341466;4853.272009198622;4855.854324370274;4857.392825896763;4858.902821943559;3516.1385460562196;3592.2276710507235;3633.5829026679485;3702.6667599916004;3766.5480142382953;3738.2803910201205;3743.1765011697385;3751.9682087425904;3756.0609847538117;3762.454444105603;3709.9443508347395;3710.3321402147603;3711.0351308659674;3714.16431302956;3733.906652333686;3540.0795793093725;3645.669330694304;3645.7265705072227;3656.634336566349;3726.832292546842;3058.1284333964713;3234.15911862915;3238.6599407062154;3245.269814291789;3246.5975178368076;4690.9765451172525;4700.882477938045;4715.504871988426;4715.7877895494785;4718.693284936468;4020.8793736187963;4238.19474299882;4241.597048280409;4250.702450828443;4257.893552661178;4992.255808143892;5013.523715247045;5019.974900125512;5029.748512574357;5040.295382893571;3475.8957231282848;3477.5826900771817;3481.234128491728;3483.9129021774406;3483.9967018963944;5297.278384525006;5347.866303342409;5355.625106242604;5364.7317634118135;5372.462753724596;3359.916002099967;3507.80707061109;3517.5904505413873;3549.538559987185;3553.1472446613143;3580.856765729394;3599.280143971216;3599.856005759793;3606.8196590170387;3610.98194509031;4186.6859985501205;4187.869275295653;4201;4203.6637069034405;4210.59999300066;4884.267359896044;5012.523810238022;5042.647014708935;5071.923921141186;5085.084684756705;1204.5543149034913;1213.3993673131856;1218.0986070307908;1218.1959906461693;1224.1308670843623;776.3851189525773;810.5744484145871;810.8175660476346;821.8315245374683;823.3536673711108;4269.765162916069;4285.857071464268;4297.226499230106;4305;4412.161689279027;5112.693238405707;5261.657992230493;5275.4460781618145;5280;5309.2301616266195;4440.069554094347;4445.377647129404;4446.755428451466;4471.977640111779;4472.457002334189;2603.0108558747556;2689.9596506052426;2712.986435067812;2715.8098933074493;2720.211138769774;4782.952170478251;4784.521547845257;4805.519448055166;4805.687630304019;4824.34871292371;5251.8161864335325;5251.868703282448;5263.184206448007;5267.236250743621;5272.789088436496;4654.37165982588;4766.499517550746;4777.784999675023;4813.927791083137;4822.590079843182;5094.898102038012;5109.233614957786;5121.8719532012;5131.409887862944;5146.794128234828;4992.425871024879;5015.210473997397;5016.2224855147515;5031.163625276821;5033.0688822567945;4605.217113090715;4704.764761761967;4761.285807128958;4776.976115119541;4816.783244754065;3444.311137772506;3455.844486998041;3460.7750496217664;3463.7575369723877;3471.4098603238017;5054.158839282246;5074.6194035448225;5097.133487307093;5098.694078355311;5100.69395836251;4826.7827947743135;4918.827841025547;4949.57928576075;4964.379452568429;5100.6429549931145;5126.7949282029795;5130.947620951649;5133.332666753298;5139.537441630271;5142.434332223377;5050;5052.267421223828;5066.898662026665;5075.720835354091;5087.592992560661;4926.778294976806;5119.87200319995;5145.4563630911325;5175.741212939262;5181.637285389987;4812.374391329105;4905.558499735041;4922.532359425849;5043.679907242709;5062.82280120194 08:49:31 INFO - PROCESS | 1336 | |5;yui.html;617.8115674719211;653.9411452969232;693.2755270742073;708.7193352152996;710.9903936410296;12774.489020439183;13888.722225555483;13930.233837138965;13933.303334833256;13984.580462586113;12922.93538532306;13035.41340639671;13039.934800325986;13815.240161791098;13952.162870227769;600.1747597054051;756.7162314132214;772.3820943245381;776.0609662308614;776.2237762237762;633.645158711121;642.8521440068764;644.0725355488082;741.3883546074483;767.5509826751357;779.3103103753191;781.5115552779513;782.9686812527492;783.5259667900923;795.4312666443492;776.8096816279991;776.9067711874554;777.0752804163028;779.2129948751744;780.8008957715803;469.44370920459363;490.06642346330295;566.7846218437008;570.1447828257614;570.4295704295704;13350.532731354357;14007.64980875476;14041.157530548098;14056.789148162785;14097.36561854719;12957.22256664603;13054.804177937338;13427.530036448677;13436.596902092855;13479.528216512375;11946.163768536206;11961.342126183057;12154.756904861943;12183.269003859798;12201;4105.466289382391;4128.339465685476;4135.669146468275;4145.875623731293;4169.97915010426;67936.66031669859;69090.65454672744;70552.29447705508;70893;70986.64506677433;10197;10376.578210481115;10574.365538067741;10595.629152979764;10627.36235825853;19877.60244795125;20000.799991999895;20075.698864517042;20119.195232190843;20120;6737.461003119739;6763.797086087426;6791.830204244933;6862.245153033262;7072.186698529632;3488.864425438227;3518.8036067737094;3525;3557.9439439039515;3603.3333833240936;2549.3371723351693;2629.447815958632;2642.8017898657677;2669.5328317544354;2677.5849743289823;436.3934131557138;437.7132977899481;445.9197344478025;452.54971303553174;455.703792534855;549.8130635583921;555.5916401444935;558.7541481748099;559.9384067752544;576.9798057067983;7469.141048779351;7704.114026886868;7734.4585878989055;7745.767626971087;7900.328472079823;463.57582811727565;513.4403500184817;544.4201924949915;555.3169601390232;556.8719194585268;3503.667151620592;3673.8714145004797;3831.444440556104;3839.347310957144;3857.479240302523;68601.42482189726;69129;69454;69992.3000769996;71971;54290.728546357015;55260.72369638126;56911;59688;62458;40007.19985600272;47450.28824567634;49891.750541247784;50234.497655022984;50313.49686503088;8543.572821359032;8660.263877570476;8904;9259.212966897889;9353.158215760614;35952.64047359493;36052.45921311235;36906.44640330451;37806;37997.810010950874;42809;49120;49320.50679493159;49484.50515494943;50843.23735143902;9378.062193780568;9405.670801521914;11044.502997364978;11165.944170279097;11386.43067846621;12435.253884766942;12469.688257793629;12960.546380876624;13088.607341779762;14018.789718154032;11658.41707914584;12359.3820308986;12359.75280494403;12548.18436801605;12821.23072615646;11338.60314888975;11486.253393529394;11508.48211830453;11689.532418703157;12051.75896482048;11797.528098876292;11903.226290291102;11995;12035.458404371999;12122.93938530337;9173.633054677739;9641.710748677551;9810.264230182907;9874.654387096418;9894.554745036634;9640.951795240979;9864.309498335047;10038.64864729731;10065.446400448045;10202.642907498202 08:49:31 INFO - PROCESS | 1336 | __end_tp_report 08:49:31 INFO - PROCESS | 1336 | __start_cc_report 08:49:31 INFO - PROCESS | 1336 | _x_x_mozilla_cycle_collect,1247 08:49:31 INFO - PROCESS | 1336 | __end_cc_report 08:49:31 INFO - PROCESS | 1336 | __startTimestamp1480438171072__endTimestamp 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | ------- Summary: start ------- 08:49:31 INFO - PROCESS | 1336 | Number of tests: 6 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | [#0] dojo.html Cycles:165 Average:4633.14 Median:4775.64 stddev:487.00 (10.2%) stddev-sans-first:460.75 08:49:31 INFO - PROCESS | 1336 | Values: 2568.0 2601.4 2615.4 2630.6 2638.6 4633.0 4657.1 4703.4 4725.1 4734.1 4711.2 4714.7 4746.1 4775.6 4805.1 4222.3 4224.4 4267.3 4291.7 4389.5 3996.0 4294.0 4361.6 4405.5 4440.3 4219.9 4258.4 4384.3 4433.7 4455.2 4260.4 4282.1 4294.2 4298.1 4361.4 4287.8 4292.5 4296.2 4300.1 4341.7 4663.3 4692.6 4708.0 4708.6 4720.4 4898.8 4945.9 4947.6 5005.1 5043.8 5050.1 5101.1 5102.8 5135.9 5139.6 4730.7 4732.2 4736.6 4744.7 4787.2 5031.2 5076.7 5202.9 5209.8 5257.5 4878.7 4893.2 4899.7 4916.5 4970.3 4994.8 4997.6 5000.3 5004.3 5050.1 4891.6 4895.8 4929.6 4947.6 4992.4 4788.7 4856.0 4856.2 4907.0 4908.2 4215.6 4221.7 4222.6 4222.9 4243.2 3738.2 3756.5 3757.4 3757.9 3759.1 4241.6 4265.5 4273.7 4276.4 4280.0 4967.6 5008.9 5016.2 5023.2 5025.6 4258.2 4292.4 4307.5 4309.6 4310.5 4502.4 4523.7 4525.5 4527.8 4561.1 4686.6 4690.1 4692.3 4695.3 4741.4 4947.0 4981.8 4986.1 5026.8 5050.7 4719.9 4721.4 4722.1 4722.9 4723.6 4897.9 4915.0 4975.3 4978.0 4978.8 4989.4 4993.2 4999.8 5014.3 5037.8 4862.0 4864.0 4870.4 4878.4 4919.2 4782.8 4792.4 4802.0 4870.5 4919.7 4787.9 4830.1 4837.4 4914.6 4924.0 4988.2 4991.1 4993.5 4998.1 5015.9 4880.3 4883.3 4886.3 4886.4 4940.1 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | [#1] ext.html Cycles:180 Average:44958.63 Median:3531.64 stddev:80283.58 (2273.3%) stddev-sans-first:80439.52 08:49:31 INFO - PROCESS | 1336 | Values: 539.5 548.8 552.6 565.7 571.2 26738.9 36471.0 37687.2 37742.4 40103.0 37620.4 37989.8 38114.2 38143.2 38153.2 7444.6 7632.2 7634.5 7634.7 7635.6 7445.4 7649.8 9564.5 9851.7 9856.9 9840.6 9882.1 9900.7 9910.6 9928.5 9844.6 9899.5 9902.8 9906.1 9922.4 9728.9 9773.9 9773.9 9781.8 9801.2 35671.6 36105.8 36125.0 36160.5 36176.6 2997.2 3048.1 3108.9 3285.8 3340.3 27486.6 27715.3 27723.2 28465.6 28498.4 3430.6 3448.4 3499.5 3525.0 3538.3 242479.0 243171.0 243254.0 243683.0 244198.0 42634.8 42695.8 42738.0 42790.6 42790.8 50779.5 50829.7 50838.0 50941.0 51021.5 12558.2 12791.7 12793.7 12859.2 12873.5 8798.2 9032.6 9035.0 9047.4 9047.4 12412.6 14059.6 14372.1 14416.4 14862.0 5567.7 5605.3 5635.2 5638.5 5646.9 780.9 965.6 968.3 971.3 1001.1 26714.3 27012.7 27014.5 27305.2 27349.2 906.3 930.7 954.2 998.3 1062.4 14346.9 14378.4 14488.8 14574.3 14670.9 340024.0 341734.0 341832.0 342435.0 388955.0 41801.0 46477.5 47509.3 47542.0 51347.2 1130.5 1141.9 1148.9 1166.9 1172.2 26507.5 27672.1 27704.9 28191.7 28427.7 35886.1 36115.6 36145.0 36146.3 36158.6 310938.0 312425.0 312465.0 312652.0 313643.0 33476.8 34104.8 34319.7 34384.8 34386.6 39318.6 40556.2 40603.2 40665.0 40665.8 33683.0 34376.3 34478.7 34599.8 34793.3 32588.7 33105.0 33108.7 33772.5 34130.3 33368.5 34047.0 34254.0 34442.5 34617.0 26812.3 26930.7 30419.4 30640.5 31626.4 24459.1 24588.5 24658.5 24862.0 24881.6 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | [#2] jquery.html Cycles:180 Average:22072.93 Median:4012.52 stddev:53640.59 (1336.8%) stddev-sans-first:53766.22 08:49:31 INFO - PROCESS | 1336 | Values: 326.1 337.7 347.0 350.7 362.8 12232.0 12275.9 13291.3 13312.7 16083.0 15200.2 15370.9 15419.2 15526.0 15617.4 4693.5 4709.4 4771.8 4888.8 4895.4 3517.2 4755.5 4839.6 4840.3 4844.8 3667.3 5470.8 5753.1 5758.1 5841.4 5626.9 5757.1 5842.6 5849.3 5850.1 5079.4 5082.0 5088.3 5092.2 5094.5 19102.1 19600.3 19777.8 19850.2 19924.5 1880.6 1889.3 1921.3 1926.5 2180.3 11044.6 11678.4 12474.4 12586.7 12623.9 1680.6 1688.5 1709.0 1714.5 1730.5 63652.0 65799.3 66678.0 67732.7 68201.7 9388.3 9460.6 9503.9 9537.2 9549.8 12517.3 12594.9 12621.2 12627.7 12632.9 5255.2 5275.0 5276.9 5316.5 5413.4 6144.2 6204.1 6377.8 6384.9 6396.4 3567.6 3787.2 3985.6 4039.4 4211.8 1594.6 1653.3 1691.7 1707.7 1727.9 830.6 879.3 880.4 896.8 933.1 15475.5 15914.9 15993.2 16179.0 16720.4 825.3 831.0 834.7 857.6 890.5 8734.8 8848.1 9258.5 9303.9 9363.1 261220.0 332740.0 333665.0 333995.0 334235.0 80073.0 81872.2 82219.6 83857.2 83893.6 919.1 923.0 937.2 958.9 965.1 15049.3 15091.9 15654.6 15944.8 15979.6 36838.6 37059.4 37076.8 37458.1 38940.2 49322.0 50933.0 50982.7 51288.5 51400.7 6145.5 6552.3 6756.5 6920.0 7224.4 8649.5 11625.0 11752.8 11898.9 12261.1 10155.5 10302.0 10371.4 10443.7 10569.8 9993.2 10045.7 10097.9 10297.1 10510.4 10460.2 10510.8 10572.9 10717.6 10850.6 6527.9 6541.8 6712.0 6724.4 6998.3 8141.6 9874.4 10079.5 10221.3 10522.5 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | [#3] mootools.html Cycles:180 Average:4244.16 Median:5322.19 stddev:2222.79 (41.8%) stddev-sans-first:2217.81 08:49:31 INFO - PROCESS | 1336 | Values: 1273.3 1309.8 1330.4 1569.3 1587.0 6089.1 6467.5 6468.9 6472.4 6476.9 5658.1 5869.6 6078.3 6289.4 6293.5 1933.1 1956.1 1958.0 1959.8 1970.1 1910.4 1945.2 1960.6 1967.5 1976.8 1681.3 1743.3 1906.6 1978.0 1980.3 1906.7 1914.1 1914.4 1920.1 1923.7 1942.9 1983.1 1984.1 1985.4 1988.1 6302.0 6322.7 6341.2 6444.2 6517.4 3730.6 3835.1 3837.7 3843.5 3846.8 2513.4 2554.3 2582.6 2614.4 2618.4 777.0 808.0 810.2 821.8 825.4 8266.6 8344.7 8397.7 8402.7 8411.7 3426.5 3482.6 3549.9 3553.5 3560.9 7413.2 7414.1 7417.6 7417.9 7418.1 3920.9 3922.9 3923.8 3925.4 3926.3 3604.0 3606.3 3613.2 3613.3 3618.0 3019.8 3080.2 3169.4 3173.3 3178.4 1048.4 1052.5 1054.5 1058.2 1068.7 759.7 783.3 783.4 796.7 813.9 4410.5 4499.4 4513.6 4531.5 4546.9 723.3 750.0 766.7 767.1 822.5 2993.6 3061.3 3092.5 3111.1 3147.6 7030.0 7289.6 7320.1 7557.1 7560.8 7497.3 7503.5 7511.1 7513.6 7513.7 5934.5 6077.8 6087.0 6087.8 6089.8 4329.9 4407.9 4432.2 4552.6 4786.2 6813.9 6952.4 7043.3 7056.4 7107.6 6958.5 7225.8 7232.2 7240.3 7245.8 5494.1 5574.3 5583.2 5632.3 5641.9 5879.3 5916.2 5933.1 5940.8 5949.6 5642.4 5692.1 5708.1 5779.7 5805.7 5427.2 5518.5 5541.4 5548.4 5552.3 5320.9 5323.5 5331.7 5383.7 5395.7 3798.9 3833.1 3870.5 3872.1 3921.2 3890.3 3924.7 3985.1 4002.9 4174.5 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | [#4] prototype.html Cycles:180 Average:4145.55 Median:4777.38 stddev:1240.37 (26.0%) stddev-sans-first:1209.17 08:49:31 INFO - PROCESS | 1336 | Values: 265.5 266.7 267.9 268.9 275.2 4880.5 4890.7 4903.8 4919.2 4936.9 4823.9 4853.3 4855.9 4857.4 4858.9 3516.1 3592.2 3633.6 3702.7 3766.5 3738.3 3743.2 3752.0 3756.1 3762.5 3709.9 3710.3 3711.0 3714.2 3733.9 3540.1 3645.7 3645.7 3656.6 3726.8 3058.1 3234.2 3238.7 3245.3 3246.6 4691.0 4700.9 4715.5 4715.8 4718.7 4020.9 4238.2 4241.6 4250.7 4257.9 4992.3 5013.5 5020.0 5029.7 5040.3 3475.9 3477.6 3481.2 3483.9 3484.0 5297.3 5347.9 5355.6 5364.7 5372.5 3359.9 3507.8 3517.6 3549.5 3553.1 3580.9 3599.3 3599.9 3606.8 3611.0 4186.7 4187.9 4201.0 4203.7 4210.6 4884.3 5012.5 5042.6 5071.9 5085.1 1204.6 1213.4 1218.1 1218.2 1224.1 776.4 810.6 810.8 821.8 823.4 4269.8 4285.9 4297.2 4305.0 4412.2 5112.7 5261.7 5275.4 5280.0 5309.2 4440.1 4445.4 4446.8 4472.0 4472.5 2603.0 2690.0 2713.0 2715.8 2720.2 4783.0 4784.5 4805.5 4805.7 4824.3 5251.8 5251.9 5263.2 5267.2 5272.8 4654.4 4766.5 4777.8 4813.9 4822.6 5094.9 5109.2 5121.9 5131.4 5146.8 4992.4 5015.2 5016.2 5031.2 5033.1 4605.2 4704.8 4761.3 4777.0 4816.8 3444.3 3455.8 3460.8 3463.8 3471.4 5054.2 5074.6 5097.1 5098.7 5100.7 4826.8 4918.8 4949.6 4964.4 5100.6 5126.8 5130.9 5133.3 5139.5 5142.4 5050.0 5052.3 5066.9 5075.7 5087.6 4926.8 5119.9 5145.5 5175.7 5181.6 4812.4 4905.6 4922.5 5043.7 5062.8 08:49:31 INFO - PROCESS | 1336 | 08:49:31 INFO - PROCESS | 1336 | [#5] yui.html Cycles:180 Average:15262.40 Median:449.23 stddev:19233.92 (4281.5%) stddev-sans-first:19256.44 08:49:31 INFO - PROCESS | 1336 | Values: 617.8 653.9 693.3 708.7 711.0 12774.5 13888.7 13930.2 13933.3 13984.6 12922.9 13035.4 13039.9 13815.2 13952.2 600.2 756.7 772.4 776.1 776.2 633.6 642.9 644.1 741.4 767.6 779.3 781.5 783.0 783.5 795.4 776.8 776.9 777.1 779.2 780.8 469.4 490.1 566.8 570.1 570.4 13350.5 14007.6 14041.2 14056.8 14097.4 12957.2 13054.8 13427.5 13436.6 13479.5 11946.2 11961.3 12154.8 12183.3 12201.0 4105.5 4128.3 4135.7 4145.9 4170.0 67936.7 69090.7 70552.3 70893.0 70986.6 10197.0 10376.6 10574.4 10595.6 10627.4 19877.6 20000.8 20075.7 20119.2 20120.0 6737.5 6763.8 6791.8 6862.2 7072.2 3488.9 3518.8 3525.0 3557.9 3603.3 2549.3 2629.4 2642.8 2669.5 2677.6 436.4 437.7 445.9 452.5 455.7 549.8 555.6 558.8 559.9 577.0 7469.1 7704.1 7734.5 7745.8 7900.3 463.6 513.4 544.4 555.3 556.9 3503.7 3673.9 3831.4 3839.3 3857.5 68601.4 69129.0 69454.0 69992.3 71971.0 54290.7 55260.7 56911.0 59688.0 62458.0 40007.2 47450.3 49891.8 50234.5 50313.5 8543.6 8660.3 8904.0 9259.2 9353.2 35952.6 36052.5 36906.4 37806.0 37997.8 42809.0 49120.0 49320.5 49484.5 50843.2 9378.1 9405.7 11044.5 11165.9 11386.4 12435.3 12469.7 12960.5 13088.6 14018.8 11658.4 12359.4 12359.8 12548.2 12821.2 11338.6 11486.3 11508.5 11689.5 12051.8 11797.5 11903.2 11995.0 12035.5 12122.9 9173.6 9641.7 9810.3 9874.7 9894.6 9641.0 9864.3 10038.6 10065.4 10202.6 08:49:31 INFO - PROCESS | 1336 | -------- Summary: end -------- 08:49:31 INFO - PROCESS | 1336 | 08:49:32 INFO - TEST-INFO | 1336: exit 0 08:49:32 INFO - TEST-OK | dromaeo_css | took 1122904ms 08:49:32 INFO - TEST-START | kraken 08:49:32 INFO - operating with platform_type : linux_ 08:49:32 INFO - Initialising browser for kraken test... 08:49:32 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd8rr8m/profile http://localhost:51911/getInfo.html) 08:49:36 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 08:49:36 INFO - PROCESS | 1645 | colorDepth:24 08:49:36 INFO - PROCESS | 1645 | Browser inner width/height: 1024/697 08:49:36 INFO - PROCESS | 1645 | __metrics 08:49:37 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:49:37 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:37 INFO - PROCESS | 1645 | console.error: PushService: 08:49:37 INFO - PROCESS | 1645 | stateChangeProcessEnqueue: Error transitioning state 08:49:37 INFO - PROCESS | 1645 | UnknownError 08:49:38 INFO - TEST-INFO | 1645: exit 0 08:49:38 INFO - Browser initialized. 08:49:38 INFO - Running cycle 1/1 for kraken test... 08:49:38 INFO - TEST-INFO | started process 1726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd8rr8m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:50 INFO - PROCESS | 1726 | RSS: Main: 174931968 08:49:50 INFO - PROCESS | 1726 | 08:49:53 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51911/tests/kraken/driver.html?testName=audio-beat-detection) 08:49:54 INFO - PROCESS | 1726 | RSS: Main: 235634688 08:49:54 INFO - PROCESS | 1726 | 08:49:57 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51911/tests/kraken/driver.html?testName=audio-dft) 08:49:58 INFO - PROCESS | 1726 | RSS: Main: 218132480 08:49:58 INFO - PROCESS | 1726 | 08:50:01 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51911/tests/kraken/driver.html?testName=audio-fft) 08:50:01 INFO - PROCESS | 1726 | RSS: Main: 300519424 08:50:01 INFO - PROCESS | 1726 | 08:50:04 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51911/tests/kraken/driver.html?testName=audio-oscillator) 08:50:04 INFO - PROCESS | 1726 | RSS: Main: 211759104 08:50:04 INFO - PROCESS | 1726 | 08:50:06 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51911/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:07 INFO - PROCESS | 1726 | RSS: Main: 214290432 08:50:07 INFO - PROCESS | 1726 | 08:50:10 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51911/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:11 INFO - PROCESS | 1726 | RSS: Main: 254558208 08:50:11 INFO - PROCESS | 1726 | 08:50:14 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51911/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:15 INFO - PROCESS | 1726 | RSS: Main: 247865344 08:50:15 INFO - PROCESS | 1726 | 08:50:18 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51911/tests/kraken/driver.html?testName=json-parse-financial) 08:50:19 INFO - PROCESS | 1726 | RSS: Main: 249446400 08:50:19 INFO - PROCESS | 1726 | 08:50:20 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51911/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:21 INFO - PROCESS | 1726 | RSS: Main: 213393408 08:50:21 INFO - PROCESS | 1726 | 08:50:23 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:23 INFO - PROCESS | 1726 | RSS: Main: 221630464 08:50:23 INFO - PROCESS | 1726 | 08:50:26 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:26 INFO - PROCESS | 1726 | RSS: Main: 229511168 08:50:26 INFO - PROCESS | 1726 | 08:50:29 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:50:30 INFO - PROCESS | 1726 | RSS: Main: 238403584 08:50:30 INFO - PROCESS | 1726 | 08:50:33 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:50:34 INFO - PROCESS | 1726 | RSS: Main: 234663936 08:50:34 INFO - PROCESS | 1726 | 08:50:35 INFO - PROCESS | 1726 | Cycle 1(1): loaded http://localhost:51911/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51911/tests/kraken/driver.html?testName=ai-astar) 08:50:36 INFO - PROCESS | 1726 | RSS: Main: 286945280 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | __start_tp_report 08:50:36 INFO - PROCESS | 1726 | _x_x_mozilla_page_load 08:50:36 INFO - PROCESS | 1726 | _x_x_mozilla_page_load_details 08:50:36 INFO - PROCESS | 1726 | |i|pagename|runs| 08:50:36 INFO - PROCESS | 1726 | |0;ai-astar;108;117;117;117;117;116;115;115;116;116 08:50:36 INFO - PROCESS | 1726 | |1;audio-beat-detection;163;133;130;171;134;138;132;132;150;135 08:50:36 INFO - PROCESS | 1726 | |2;audio-dft;162;165;164;163;161;162;162;161;163;162 08:50:36 INFO - PROCESS | 1726 | |3;audio-fft;76;77;77;114;77;77;75;76;96;76 08:50:36 INFO - PROCESS | 1726 | |4;audio-oscillator;84;84;104;85;84;84;101;84;84;110 08:50:36 INFO - PROCESS | 1726 | |5;imaging-gaussian-blur;117;122;117;117;121;117;118;117;117;117 08:50:36 INFO - PROCESS | 1726 | |6;imaging-darkroom;148;146;146;146;147;147;147;146;145;147 08:50:36 INFO - PROCESS | 1726 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;95 08:50:36 INFO - PROCESS | 1726 | |8;json-parse-financial;65;65;63;64;64;66;66;65;64;67 08:50:36 INFO - PROCESS | 1726 | |9;json-stringify-tinderbox;65;66;65;64;66;66;66;65;66;66 08:50:36 INFO - PROCESS | 1726 | |10;stanford-crypto-aes;89;92;87;91;85;84;85;89;85;85 08:50:36 INFO - PROCESS | 1726 | |11;stanford-crypto-ccm;156;143;143;145;147;142;139;141;185;143 08:50:36 INFO - PROCESS | 1726 | |12;stanford-crypto-pbkdf2;184;181;184;181;185;180;180;178;182;179 08:50:36 INFO - PROCESS | 1726 | |13;stanford-crypto-sha256-iterative;72;73;70;76;72;70;72;72;71;75 08:50:36 INFO - PROCESS | 1726 | __end_tp_report 08:50:36 INFO - PROCESS | 1726 | __start_cc_report 08:50:36 INFO - PROCESS | 1726 | _x_x_mozilla_cycle_collect,1990 08:50:36 INFO - PROCESS | 1726 | __end_cc_report 08:50:36 INFO - PROCESS | 1726 | __startTimestamp1480438236168__endTimestamp 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | ------- Summary: start ------- 08:50:36 INFO - PROCESS | 1726 | Number of tests: 14 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.50 stddev:2.72 (2.3%) stddev-sans-first:0.83 08:50:36 INFO - PROCESS | 1726 | Values: 108.0 117.0 117.0 117.0 117.0 116.0 115.0 115.0 116.0 116.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:136.50 stddev:14.53 (10.6%) stddev-sans-first:13.23 08:50:36 INFO - PROCESS | 1726 | Values: 163.0 133.0 130.0 171.0 134.0 138.0 132.0 132.0 150.0 135.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 08:50:36 INFO - PROCESS | 1726 | Values: 162.0 165.0 164.0 163.0 161.0 162.0 162.0 161.0 163.0 162.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#3] audio-fft Cycles:10 Average:82.10 Median:77.00 stddev:12.81 (16.6%) stddev-sans-first:13.40 08:50:36 INFO - PROCESS | 1726 | Values: 76.0 77.0 77.0 114.0 77.0 77.0 75.0 76.0 96.0 76.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.00 stddev:10.31 (12.3%) stddev-sans-first:10.67 08:50:36 INFO - PROCESS | 1726 | Values: 84.0 84.0 104.0 85.0 84.0 84.0 101.0 84.0 84.0 110.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:117.00 stddev:1.89 (1.6%) stddev-sans-first:1.96 08:50:36 INFO - PROCESS | 1726 | Values: 117.0 122.0 117.0 117.0 121.0 117.0 118.0 117.0 117.0 117.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.85 (0.6%) stddev-sans-first:0.71 08:50:36 INFO - PROCESS | 1726 | Values: 148.0 146.0 146.0 146.0 147.0 147.0 147.0 146.0 145.0 147.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:50:36 INFO - PROCESS | 1726 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 08:50:36 INFO - PROCESS | 1726 | Values: 65.0 65.0 63.0 64.0 64.0 66.0 66.0 65.0 64.0 67.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 08:50:36 INFO - PROCESS | 1726 | Values: 65.0 66.0 65.0 64.0 66.0 66.0 66.0 65.0 66.0 66.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:88.00 stddev:2.86 (3.2%) stddev-sans-first:2.96 08:50:36 INFO - PROCESS | 1726 | Values: 89.0 92.0 87.0 91.0 85.0 84.0 85.0 89.0 85.0 85.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:144.00 stddev:13.67 (9.5%) stddev-sans-first:14.22 08:50:36 INFO - PROCESS | 1726 | Values: 156.0 143.0 143.0 145.0 147.0 142.0 139.0 141.0 185.0 143.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:181.50 stddev:2.32 (1.3%) stddev-sans-first:2.26 08:50:36 INFO - PROCESS | 1726 | Values: 184.0 181.0 184.0 181.0 185.0 180.0 180.0 178.0 182.0 179.0 08:50:36 INFO - PROCESS | 1726 | 08:50:36 INFO - PROCESS | 1726 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.30 Median:72.00 stddev:1.95 (2.7%) stddev-sans-first:2.06 08:50:36 INFO - PROCESS | 1726 | Values: 72.0 73.0 70.0 76.0 72.0 70.0 72.0 72.0 71.0 75.0 08:50:36 INFO - PROCESS | 1726 | -------- Summary: end -------- 08:50:36 INFO - PROCESS | 1726 | 08:50:37 INFO - TEST-INFO | 1726: exit 0 08:50:37 INFO - TEST-OK | kraken | took 65383ms 08:50:37 INFO - SUITE-END | took 1188s 08:50:37 INFO - Completed test suite (00:19:48) 08:50:37 INFO - javascript benchmark 08:50:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.9614805777915, 2601.3756698392367, 2615.411532405209, 2630.6054091886213, 2638.63059171716, 4632.980744236271, 4657.06858628274, 4703.412073490814, 4725.149473094842, 4734.076855013273, 4711.151992641323, 4714.740689262089, 4746.0507898420365, 4775.641826862981, 4805.135075686375, 4222.303319952204, 4224.408582798395, 4267.317229243322, 4291.699581029329, 4389.47326320842, 3995.956748335404, 4294.012377153256, 4361.6292615127595, 4405.537418571052, 4440.289553671413, 4219.873403797891, 4258.446401967755, 4384.320430333303, 4433.667474939392, 4455.198064348416, 4260.403543503912, 4282.100758840647, 4294.184105020067, 4298.118885628439, 4361.389405483234, 4287.8070486828165, 4292.506361768405, 4296.162248361571, 4300.1399720056115, 4341.696081274312, 4663.253879379283, 4692.554207350297, 4707.9877826267175, 4708.576228139484, 4720.43354797423, 4898.804047838118, 4945.851624451237, 4947.628927830391, 5005.0990821652085, 5043.773030213612, 5050.065737838511, 5101.056304583627, 5102.821401250939, 5135.897282054338, 5139.614528910347, 4730.692504987199, 4732.242841145469, 4736.5973892219545, 4744.69159504638, 4787.162246606899, 5031.22016087507, 5076.720780357042, 5202.947970520247, 5209.843704688866, 5257.5005374489365, 4878.707277563358, 4893.168161412568, 4899.681520701143, 4916.483769204182, 4970.329005584272, 4994.750262486934, 4997.550220480174, 5000.349954505891, 5004.324416203839, 5050.065737838511, 4891.63312751538, 4895.804167833318, 4929.605631549467, 4947.579455746302, 4992.425871024879, 4788.688735232199, 4855.95144048555, 4856.150173719585, 4907.0, 4908.214685650279, 4215.59951804578, 4221.7255878367805, 4222.59885310895, 4222.936655950163, 4243.236217480883, 3738.1776009277914, 3756.511653485084, 3757.3988161893967, 3757.868474603376, 3759.0602349412648, 4241.63946064588, 4265.488141422987, 4273.722208056466, 4276.35854621803, 4279.957200428018, 4967.627427942846, 5008.949910500848, 5016.1723315653235, 5023.196288593807, 5025.597952163818, 4258.1696569169335, 4292.420523229386, 4307.461567304087, 4309.633681137138, 4310.52584215736, 4502.414686090788, 4523.660725445605, 4525.524819893863, 4527.796249168729, 4561.064981678748, 4686.64850136248, 4690.061987602493, 4692.272697731857, 4695.319178718998, 4741.431028276629, 4947.035328110984, 4981.800727970841, 4986.052649996489, 5026.824061157842, 5050.747462626928, 4719.882002949954, 4721.433427988663, 4722.1028004679, 4722.929156062593, 4723.622110231105, 4897.9020419592125, 4914.975425122851, 4975.328330675457, 4977.9751101244265, 4978.775955081957, 4989.376327959005, 4993.15116430215, 4999.775055111521, 5014.34813474246, 5037.823676171316, 4862.0, 4864.0, 4870.391201099863, 4878.414590249051, 4919.1883339248625, 4782.832600858953, 4792.448868380042, 4802.039592081528, 4870.537298956593, 4919.704817710949, 4787.928181077356, 4830.058138662927, 4837.443693975238, 4914.606831453403, 4924.0, 4988.201887697951, 4991.101601711582, 4993.525615066563, 4998.125328067501, 5015.899682006412, 4880.341153944314, 4883.291922671122, 4886.291487734187, 4886.364772579542, 4940.061388336205], "value": 4601.943034338309, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.4686234059452, 548.8079172289697, 552.5551930695789, 565.7482420322958, 571.1575426246287, 26738.866305668467, 36471.0, 37687.24625507488, 37742.43386349207, 40103.0, 37620.43569346462, 37989.810050949774, 38114.237715245676, 38143.23713525735, 38153.23693526128, 7444.553326800396, 7632.198619144993, 7634.5037572557885, 7634.694612215505, 7635.618219089051, 7445.4415918806035, 7649.847003059936, 9564.473953932531, 9851.655192068243, 9856.901430985707, 9840.606375744961, 9882.061204185591, 9900.653477128302, 9910.55402506889, 9928.503574821267, 9844.606215751397, 9899.505024748734, 9902.801943961153, 9906.108450239477, 9922.40465572068, 9728.90271097287, 9773.853392199122, 9773.902260977371, 9781.755456113582, 9801.215902727836, 35671.64328356709, 36105.81947090274, 36125.0, 36160.457593136125, 36176.63823361785, 2997.2357048952595, 3048.1465189746687, 3108.9067327980197, 3285.802851828898, 3340.331933613287, 27486.587701184497, 27715.307117322027, 27723.168304950883, 28465.57301640477, 28498.430031399257, 3430.5540279763472, 3448.448248280288, 3499.510068590374, 3525.0306165804354, 3538.2569660371355, 242479.0, 243171.0, 243254.0, 243682.99999999822, 244198.0, 42634.78682606567, 42695.786521067195, 42738.0, 42790.57209427866, 42790.786046069574, 50779.49220507822, 50829.74585127125, 50838.0, 50941.0, 51021.48978510167, 12558.246505209716, 12791.744165116646, 12793.744125117446, 12859.16415432994, 12873.549425770054, 8798.164174403422, 9032.593533291018, 9034.954825225832, 9047.411918225294, 9047.411918225425, 12412.62762117138, 14059.648508787363, 14372.137671739729, 14416.351264193136, 14862.0, 5567.749451274703, 5605.327360716658, 5635.182898479779, 5638.499128967703, 5646.887062258732, 780.8711562592109, 965.6041023180462, 968.3270127261626, 971.3152227679469, 1001.1040119093516, 26714.332141696614, 27012.729872701413, 27014.459710806066, 27305.180844574694, 27349.179524614294, 906.2885634776669, 930.7161315798746, 954.1651055326589, 998.2962011781676, 1062.4156713807374, 14346.928265358607, 14378.35297411619, 14488.782668259984, 14574.344154513072, 14670.853291467161, 340024.0, 341734.0, 341832.0, 342435.0, 388955.0, 41801.0, 46477.535224647996, 47509.28736068962, 47542.0, 51347.22979155316, 1130.4630300607146, 1141.8515592973026, 1148.9023433008122, 1166.877477864829, 1172.1560476456937, 26507.469850603266, 27672.132458406937, 27704.86147569249, 28191.71808281891, 28427.715722842506, 35886.10284742798, 36115.638843611225, 36145.0, 36146.27707445784, 36158.638413615525, 310938.0, 312425.0, 312465.0, 312652.0, 313643.0, 33476.83261583676, 34104.829475852464, 34319.656803431644, 34384.82807585946, 34386.56134386541, 39318.60681393264, 40556.1888762229, 40603.1879362417, 40665.0, 40665.796671016455, 33683.0, 34376.312473750884, 34478.65521344855, 34599.82700086483, 34793.304133916674, 32588.674113258563, 33105.0, 33108.66891331056, 33772.493412599324, 34130.317393651494, 33368.49947250842, 34047.0, 34254.0, 34442.48336275008, 34617.0, 26812.32969175708, 26930.73069269282, 30419.39161216719, 30640.540391893694, 31626.36747265088, 24459.143929962367, 24588.50822983566, 24658.50682986366, 24862.0, 24881.62677559802], "value": 17038.651967843743, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.1211036257288, 337.7061956098195, 346.96356882527334, 350.6948954409663, 362.802272761345, 12232.0, 12275.877241227585, 13291.335433228336, 13312.667183320398, 16083.035017898934, 15200.16399098049, 15370.923145384257, 15419.15194664293, 15525.990810597303, 15617.375304987787, 4693.460252071013, 4709.411323584552, 4771.761411929389, 4888.828890988816, 4895.36360273164, 3517.155882588186, 4755.45312289086, 4839.5644392004715, 4840.298156767267, 4844.830430934918, 3667.2665466906724, 5470.835874923759, 5753.108268218432, 5758.078707406816, 5841.357450680422, 5626.915596266059, 5757.050086735683, 5842.591018628697, 5849.327327357366, 5850.122481627769, 5079.390473143209, 5081.974590127063, 5088.313077734496, 5092.210707340369, 5094.490550944913, 19102.140403681868, 19600.313989010316, 19777.802221977883, 19850.20599176031, 19924.501887452785, 1880.6332765110672, 1889.3104017033684, 1921.28912302449, 1926.489480287725, 2180.2805074325433, 11044.558217671363, 11678.416079196091, 12474.43865026076, 12586.685332866751, 12623.93688031554, 1680.5546530169515, 1688.4765722626025, 1708.982910170895, 1714.4942242038503, 1730.4635562975518, 63652.0, 65799.3420065798, 66678.0, 67732.661336693, 68201.65899170522, 9388.342816002814, 9460.574274157541, 9503.857442138373, 9537.2370210383, 9549.75580738526, 12517.311547864772, 12594.874051259554, 12621.179623324455, 12627.747445051047, 12632.936835315764, 5255.185446255837, 5274.97362513185, 5276.947230527646, 5316.548093412026, 5413.377461591967, 6144.2319710036245, 6204.13142160098, 6377.840553986095, 6384.904226436607, 6396.392342727433, 3567.5897271813556, 3787.2046870157023, 3985.601439855991, 4039.434479172918, 4211.831526738896, 1594.5614955886992, 1653.297348626853, 1691.6954948109212, 1707.6840784454666, 1727.9308827646753, 830.5515021888112, 879.3404946290283, 880.4101252161067, 896.8071864549023, 933.0855761353776, 15475.535733928, 15914.92042539803, 15993.200339983186, 16178.984514800215, 16720.41478548245, 825.2613910550024, 830.9667613295521, 834.6953362022939, 857.6312185760183, 890.5413711938357, 8734.781630459162, 8848.1151884811, 9258.490783006864, 9303.906960930304, 9363.110504502061, 261220.0, 332740.0, 333665.0, 333995.0, 334235.0, 80073.0, 81872.18127818646, 82219.5889020563, 83857.16142838616, 83893.58053209695, 919.0763282900642, 923.0308176414737, 937.231470194434, 958.9328746987784, 965.0735294117726, 15049.322780475124, 15091.924540377227, 15654.608634784221, 15944.840551594336, 15979.600509987344, 36838.579370475716, 37059.444108337855, 37076.81461592675, 37458.06354841042, 38940.221195576494, 49322.0, 50933.0, 50982.74508627433, 51288.48711512837, 51400.74299628478, 6145.477634401126, 6552.27924928267, 6756.493263005196, 6919.965400172967, 7224.422046236394, 8649.481031138152, 11625.0, 11752.823707644564, 11898.881011189776, 12261.141720079866, 10155.543000564845, 10302.0, 10371.377717336984, 10443.68668939933, 10569.841452378067, 9993.200543956611, 10045.69862904114, 10097.949510252402, 10297.07326340603, 10510.421926794099, 10460.215483838894, 10510.842337364793, 10572.8942710575, 10717.571297148026, 10850.620228291971, 6527.934720652923, 6541.803745887631, 6711.966440167768, 6724.394804467622, 6998.300169982961, 8141.552214628252, 9874.358166719428, 10079.496025198858, 10221.28451008452, 10522.475700257426], "value": 7831.2131500874075, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.3378643105586, 1309.8428188617365, 1330.4079684540384, 1569.285974881429, 1587.0398408962578, 6089.14751934729, 6467.482601391889, 6468.902966455507, 6472.4174824265865, 6476.935230647692, 5658.122990936401, 5869.618474799135, 6078.300995385532, 6289.402506761861, 6293.5279854010905, 1933.0624647046172, 1956.0610906764762, 1957.9510512237166, 1959.804019598036, 1970.123295133666, 1910.436421255726, 1945.221911235503, 1960.5980773941308, 1967.478618166187, 1976.7825539190678, 1681.3274690123988, 1743.2591148761726, 1906.6186762647526, 1978.0307649251836, 1980.2969945669297, 1906.6853969095082, 1914.0716752375088, 1914.3778272061636, 1920.0783623860555, 1923.7210604462275, 1942.9319973800991, 1983.1175127068461, 1984.087319832864, 1985.434151266882, 1988.0556735550642, 6302.0, 6322.683865806691, 6341.1756471658855, 6444.194475690539, 6517.3808488193545, 3730.570984336809, 3835.0987517933263, 3837.654611085016, 3843.500344955166, 3846.769193848378, 2513.423740665814, 2554.3358726731, 2582.57387531059, 2614.35948192694, 2618.4370360372423, 776.97669069928, 807.9515229086273, 810.1533897077509, 821.7699044267555, 825.4387016828554, 8266.586670666442, 8344.749657510285, 8397.664093436317, 8402.705905293285, 8411.663533458654, 3426.4860270959057, 3482.59950105736, 3549.8580056797955, 3553.5202747629255, 3560.9109772255383, 7413.221611730691, 7414.073240844895, 7417.554946703215, 7417.925820741723, 7418.109826820816, 3920.882373528799, 3922.8626998054933, 3923.843046278174, 3925.371940489508, 3926.312895243321, 3604.044928094057, 3606.2787442511603, 3613.2231570212007, 3613.2954073955325, 3618.0412190769466, 3019.8326309692484, 3080.245339891741, 3169.3661267746083, 3173.2701478660038, 3178.4437723398314, 1048.3709774135457, 1052.5158427123608, 1054.5149231353664, 1058.2486434631344, 1068.738159151013, 759.6999185321766, 783.3380793229712, 783.4046124945082, 796.7012370361115, 813.943023988327, 4410.470743510799, 4499.370088187657, 4513.638908887281, 4531.478879928851, 4546.931796023062, 723.2947875821033, 749.966251518672, 766.6818270417714, 767.0764041925511, 822.5352675738188, 2993.5659329397117, 3061.341811510536, 3092.5206592978125, 3111.0822307419367, 3147.638021627542, 7029.964850175818, 7289.562626242442, 7320.084989376328, 7557.093148822067, 7560.810979725551, 7497.250274972459, 7503.512271702322, 7511.06111736033, 7513.586752728543, 7513.737019204302, 5934.525237980951, 6077.75688972445, 6087.0, 6087.817365479043, 6089.756409743561, 4329.891752706208, 4407.9118417630825, 4432.202203603382, 4552.635789136927, 4786.234202527578, 6813.89779153323, 6952.409045231212, 7043.295670432915, 7056.435485161277, 7107.609081500567, 6958.478114141359, 7225.819354516179, 7232.168300645493, 7240.275972402717, 7245.818854528679, 5494.09347457665, 5574.303212098488, 5583.2462617547735, 5632.295963004624, 5641.887162256649, 5879.323877754113, 5916.171735956884, 5933.1396947443245, 5940.79207227745, 5949.613275137102, 5642.351129619982, 5692.146178073156, 5708.143778433102, 5779.739911704068, 5805.680687562224, 5427.185922111715, 5518.503334699896, 5541.3904470509015, 5548.417416171405, 5552.278203833475, 5320.920186197132, 5323.4676532348, 5331.680099194061, 5383.730813459233, 5395.730213489232, 3798.905027374338, 3833.0992216828467, 3870.53553573573, 3872.090058836115, 3921.1961547882047, 3890.2608504384075, 3924.6664033557468, 3985.103351745834, 4002.9199416012098, 4174.540800511881], "value": 3507.1354654717697, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.4703865787754, 266.74392583120215, 267.9115891755721, 268.9166358428887, 275.21426327834047, 4880.536349046839, 4890.679516530539, 4903.8230824602015, 4919.237518184678, 4936.851894443168, 4823.855284341466, 4853.272009198622, 4855.854324370274, 4857.392825896763, 4858.902821943559, 3516.1385460562196, 3592.2276710507235, 3633.5829026679485, 3702.6667599916004, 3766.5480142382953, 3738.2803910201205, 3743.1765011697385, 3751.9682087425904, 3756.0609847538117, 3762.454444105603, 3709.9443508347395, 3710.3321402147603, 3711.0351308659674, 3714.16431302956, 3733.906652333686, 3540.0795793093725, 3645.669330694304, 3645.7265705072227, 3656.634336566349, 3726.832292546842, 3058.1284333964713, 3234.15911862915, 3238.6599407062154, 3245.269814291789, 3246.5975178368076, 4690.9765451172525, 4700.882477938045, 4715.504871988426, 4715.7877895494785, 4718.693284936468, 4020.8793736187963, 4238.19474299882, 4241.597048280409, 4250.702450828443, 4257.893552661178, 4992.255808143892, 5013.523715247045, 5019.974900125512, 5029.748512574357, 5040.295382893571, 3475.8957231282848, 3477.5826900771817, 3481.234128491728, 3483.9129021774406, 3483.9967018963944, 5297.278384525006, 5347.866303342409, 5355.625106242604, 5364.7317634118135, 5372.462753724596, 3359.916002099967, 3507.80707061109, 3517.5904505413873, 3549.538559987185, 3553.1472446613143, 3580.856765729394, 3599.280143971216, 3599.856005759793, 3606.8196590170387, 3610.98194509031, 4186.6859985501205, 4187.869275295653, 4201.0, 4203.6637069034405, 4210.59999300066, 4884.267359896044, 5012.523810238022, 5042.647014708935, 5071.923921141186, 5085.084684756705, 1204.5543149034913, 1213.3993673131856, 1218.0986070307908, 1218.1959906461693, 1224.1308670843623, 776.3851189525773, 810.5744484145871, 810.8175660476346, 821.8315245374683, 823.3536673711108, 4269.765162916069, 4285.857071464268, 4297.226499230106, 4305.0, 4412.161689279027, 5112.693238405707, 5261.657992230493, 5275.4460781618145, 5280.0, 5309.2301616266195, 4440.069554094347, 4445.377647129404, 4446.755428451466, 4471.977640111779, 4472.457002334189, 2603.0108558747556, 2689.9596506052426, 2712.986435067812, 2715.8098933074493, 2720.211138769774, 4782.952170478251, 4784.521547845257, 4805.519448055166, 4805.687630304019, 4824.34871292371, 5251.8161864335325, 5251.868703282448, 5263.184206448007, 5267.236250743621, 5272.789088436496, 4654.37165982588, 4766.499517550746, 4777.784999675023, 4813.927791083137, 4822.590079843182, 5094.898102038012, 5109.233614957786, 5121.8719532012, 5131.409887862944, 5146.794128234828, 4992.425871024879, 5015.210473997397, 5016.2224855147515, 5031.163625276821, 5033.0688822567945, 4605.217113090715, 4704.764761761967, 4761.285807128958, 4776.976115119541, 4816.783244754065, 3444.311137772506, 3455.844486998041, 3460.7750496217664, 3463.7575369723877, 3471.4098603238017, 5054.158839282246, 5074.6194035448225, 5097.133487307093, 5098.694078355311, 5100.69395836251, 4826.7827947743135, 4918.827841025547, 4949.57928576075, 4964.379452568429, 5100.6429549931145, 5126.7949282029795, 5130.947620951649, 5133.332666753298, 5139.537441630271, 5142.434332223377, 5050.0, 5052.267421223828, 5066.898662026665, 5075.720835354091, 5087.592992560661, 4926.778294976806, 5119.87200319995, 5145.4563630911325, 5175.741212939262, 5181.637285389987, 4812.374391329105, 4905.558499735041, 4922.532359425849, 5043.679907242709, 5062.82280120194], "value": 3739.2988751629473, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.8115674719211, 653.9411452969232, 693.2755270742073, 708.7193352152996, 710.9903936410296, 12774.489020439183, 13888.722225555483, 13930.233837138965, 13933.303334833256, 13984.580462586113, 12922.93538532306, 13035.41340639671, 13039.934800325986, 13815.240161791098, 13952.162870227769, 600.1747597054051, 756.7162314132214, 772.3820943245381, 776.0609662308614, 776.2237762237762, 633.645158711121, 642.8521440068764, 644.0725355488082, 741.3883546074483, 767.5509826751357, 779.3103103753191, 781.5115552779513, 782.9686812527492, 783.5259667900923, 795.4312666443492, 776.8096816279991, 776.9067711874554, 777.0752804163028, 779.2129948751744, 780.8008957715803, 469.44370920459363, 490.06642346330295, 566.7846218437008, 570.1447828257614, 570.4295704295704, 13350.532731354357, 14007.64980875476, 14041.157530548098, 14056.789148162785, 14097.36561854719, 12957.22256664603, 13054.804177937338, 13427.530036448677, 13436.596902092855, 13479.528216512375, 11946.163768536206, 11961.342126183057, 12154.756904861943, 12183.269003859798, 12201.0, 4105.466289382391, 4128.339465685476, 4135.669146468275, 4145.875623731293, 4169.97915010426, 67936.66031669859, 69090.65454672744, 70552.29447705508, 70893.0, 70986.64506677433, 10197.0, 10376.578210481115, 10574.365538067741, 10595.629152979764, 10627.36235825853, 19877.60244795125, 20000.799991999895, 20075.698864517042, 20119.195232190843, 20120.0, 6737.461003119739, 6763.797086087426, 6791.830204244933, 6862.245153033262, 7072.186698529632, 3488.864425438227, 3518.8036067737094, 3525.0, 3557.9439439039515, 3603.3333833240936, 2549.3371723351693, 2629.447815958632, 2642.8017898657677, 2669.5328317544354, 2677.5849743289823, 436.3934131557138, 437.7132977899481, 445.9197344478025, 452.54971303553174, 455.703792534855, 549.8130635583921, 555.5916401444935, 558.7541481748099, 559.9384067752544, 576.9798057067983, 7469.141048779351, 7704.114026886868, 7734.4585878989055, 7745.767626971087, 7900.328472079823, 463.57582811727565, 513.4403500184817, 544.4201924949915, 555.3169601390232, 556.8719194585268, 3503.667151620592, 3673.8714145004797, 3831.444440556104, 3839.347310957144, 3857.479240302523, 68601.42482189726, 69129.0, 69454.0, 69992.3000769996, 71971.0, 54290.728546357015, 55260.72369638126, 56911.0, 59688.0, 62458.0, 40007.19985600272, 47450.28824567634, 49891.750541247784, 50234.497655022984, 50313.49686503088, 8543.572821359032, 8660.263877570476, 8904.0, 9259.212966897889, 9353.158215760614, 35952.64047359493, 36052.45921311235, 36906.44640330451, 37806.0, 37997.810010950874, 42809.0, 49120.0, 49320.50679493159, 49484.50515494943, 50843.23735143902, 9378.062193780568, 9405.670801521914, 11044.502997364978, 11165.944170279097, 11386.43067846621, 12435.253884766942, 12469.688257793629, 12960.546380876624, 13088.607341779762, 14018.789718154032, 11658.41707914584, 12359.3820308986, 12359.75280494403, 12548.18436801605, 12821.23072615646, 11338.60314888975, 11486.253393529394, 11508.48211830453, 11689.532418703157, 12051.75896482048, 11797.528098876292, 11903.226290291102, 11995.0, 12035.458404371999, 12122.93938530337, 9173.633054677739, 9641.710748677551, 9810.264230182907, 9874.654387096418, 9894.554745036634, 9640.951795240979, 9864.309498335047, 10038.64864729731, 10065.446400448045, 10202.642907498202], "value": 6256.766163178716, "unit": "score"}], "name": "dromaeo_css", "value": 6077.509994684891, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 117.0, 117.0, 117.0, 116.0, 115.0, 115.0, 116.0, 116.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 133.0, 130.0, 171.0, 134.0, 138.0, 132.0, 132.0, 150.0, 135.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 164.0, 163.0, 161.0, 162.0, 162.0, 161.0, 163.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 114.0, 77.0, 77.0, 75.0, 76.0, 96.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 104.0, 85.0, 84.0, 84.0, 101.0, 84.0, 84.0, 110.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 117.0, 117.0, 121.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 145.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 64.0, 64.0, 66.0, 66.0, 65.0, 64.0, 67.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 64.0, 66.0, 66.0, 66.0, 65.0, 66.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 87.0, 91.0, 85.0, 84.0, 85.0, 89.0, 85.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 143.0, 143.0, 145.0, 147.0, 142.0, 139.0, 141.0, 185.0, 143.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 184.0, 181.0, 185.0, 180.0, 180.0, 178.0, 182.0, 179.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 70.0, 76.0, 72.0, 70.0, 72.0, 72.0, 71.0, 75.0], "value": 72.3, "unit": "score"}], "name": "kraken", "value": 1571.5000000000002, "alertThreshold": 2.0}]} 08:50:37 INFO - Return code: 0 08:50:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:50:37 INFO - # TBPL SUCCESS # 08:50:37 INFO - Running post-action listener: _resource_record_post_action 08:50:37 INFO - [mozharness: 2016-11-29 16:50:37.947211Z] Finished run-tests step (success) 08:50:37 INFO - Running post-run listener: _resource_record_post_run 08:50:38 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401825792; Read time: 12; Write time: 339412 08:50:38 INFO - TinderboxPrint: CPU usage
13.1% 08:50:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:50:38 INFO - TinderboxPrint: I/O write bytes / time
401,825,792 / 339,412 08:50:38 INFO - TinderboxPrint: CPU idle
8,353.8 (86.9%) 08:50:38 INFO - TinderboxPrint: CPU user
1,210.4 (12.6%) 08:50:38 INFO - TinderboxPrint: Swap in / out
0 / 0 08:50:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99926016; Read time: 0; Write time: 104756 08:50:38 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 290349056; Read time: 0; Write time: 230088 08:50:38 INFO - Running post-run listener: _upload_blobber_files 08:50:38 INFO - Blob upload gear active. 08:50:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:50:38 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 08:50:38 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:50:38 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:50:39 INFO - (blobuploader) - INFO - Open directory for files ... 08:50:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:50:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:42 INFO - (blobuploader) - INFO - Done attempting. 08:50:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:50:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:50:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:43 INFO - (blobuploader) - INFO - Done attempting. 08:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:44 INFO - (blobuploader) - INFO - Done attempting. 08:50:44 INFO - (blobuploader) - INFO - Iteration through files over. 08:50:44 INFO - Return code: 0 08:50:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:50:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:50:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/46bf026644c54b5d5036b513981eff5ff8c81ad871698f020e2cf3b46da06b5bc1d8b0715ae58b257d61527f0ad6a6e09e3fbfa0ba426bcfd1858d93eb3dda0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/4c8918ab7f90ae0bcdc0c25f98f9a540ebbed29c6e677420f44a2e41ebf19953c80d0d1086076ab9783292917476a1a98517b7f4e8302320d2cbff39d8a02dbc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df086bcfe2be59802a6dfbdf66e40579e6e0514f54516c109e0d64e74b58f98a1f931a4f55ef05832c11d0bbe53270d6c94c8b5ee5372beadedd03ebf434ac34"} 08:50:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:50:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:50:44 INFO - Contents: 08:50:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/46bf026644c54b5d5036b513981eff5ff8c81ad871698f020e2cf3b46da06b5bc1d8b0715ae58b257d61527f0ad6a6e09e3fbfa0ba426bcfd1858d93eb3dda0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/4c8918ab7f90ae0bcdc0c25f98f9a540ebbed29c6e677420f44a2e41ebf19953c80d0d1086076ab9783292917476a1a98517b7f4e8302320d2cbff39d8a02dbc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df086bcfe2be59802a6dfbdf66e40579e6e0514f54516c109e0d64e74b58f98a1f931a4f55ef05832c11d0bbe53270d6c94c8b5ee5372beadedd03ebf434ac34"} 08:50:44 INFO - Running post-run listener: copy_logs_to_upload_dir 08:50:44 INFO - Copying logs to upload dir... 08:50:44 INFO - mkdir: /builds/slave/test/build/upload/logs 08:50:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.834792 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-11-29 08:50:44.098013) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 08:50:44.101416) ========= 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=41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/46bf026644c54b5d5036b513981eff5ff8c81ad871698f020e2cf3b46da06b5bc1d8b0715ae58b257d61527f0ad6a6e09e3fbfa0ba426bcfd1858d93eb3dda0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/4c8918ab7f90ae0bcdc0c25f98f9a540ebbed29c6e677420f44a2e41ebf19953c80d0d1086076ab9783292917476a1a98517b7f4e8302320d2cbff39d8a02dbc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df086bcfe2be59802a6dfbdf66e40579e6e0514f54516c109e0d64e74b58f98a1f931a4f55ef05832c11d0bbe53270d6c94c8b5ee5372beadedd03ebf434ac34"} build_url:https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011604 build_url: 'https://queue.taskcluster.net/v1/task/cl4VJLBnTcyV0OdyLfPsOg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/46bf026644c54b5d5036b513981eff5ff8c81ad871698f020e2cf3b46da06b5bc1d8b0715ae58b257d61527f0ad6a6e09e3fbfa0ba426bcfd1858d93eb3dda0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/4c8918ab7f90ae0bcdc0c25f98f9a540ebbed29c6e677420f44a2e41ebf19953c80d0d1086076ab9783292917476a1a98517b7f4e8302320d2cbff39d8a02dbc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/df086bcfe2be59802a6dfbdf66e40579e6e0514f54516c109e0d64e74b58f98a1f931a4f55ef05832c11d0bbe53270d6c94c8b5ee5372beadedd03ebf434ac34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 08:50:44.128449) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:50:44.128829) ========= 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=41a7e32711b8e08598a63237000001d6-1480433481.522923-847037384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 08:50:44.214646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 08:50:44.215049) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 08:50:44.215377) ========= ========= Total master_lag: 0.20 =========