builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-025 starttime: 1483808860.19 results: success (0) revision: e9d16569a7b4518e3a90a0512128b838118dea55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.192284) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.192703) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.192972) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.207215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.207479) ========= 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=232498196343f89f466a4a9a000001c8-1483808861.122813-290819486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-07 09:07:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 28.2M=0s 2017-01-07 09:07:41 (28.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113892 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.335009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.335331) ========= 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=232498196343f89f466a4a9a000001c8-1483808861.122813-290819486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019860 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.384961) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:40.385312) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e9d16569a7b4518e3a90a0512128b838118dea55 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev e9d16569a7b4518e3a90a0512128b838118dea55 --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=232498196343f89f466a4a9a000001c8-1483808861.122813-290819486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-07 09:07:41,971 truncating revision to first 12 chars 2017-01-07 09:07:41,971 Setting DEBUG logging. 2017-01-07 09:07:41,971 attempt 1/10 2017-01-07 09:07:41,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e9d16569a7b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 09:07:42,320 unpacking tar archive at: mozilla-central-e9d16569a7b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.639401 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:41.038016) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:41.038344) ========= script_repo_revision: e9d16569a7b4518e3a90a0512128b838118dea55 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:41.038750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:41.039010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 09:07:41.049576) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-07 09:07:41.049900) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1483808861.122813-290819486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:07:42 INFO - MultiFileLogger online at 20170107 09:07:42 in /builds/slave/test 09:07:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:07:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:42 INFO - {'append_to_log': False, 09:07:42 INFO - 'base_work_dir': '/builds/slave/test', 09:07:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:07:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:42 INFO - 'branch': 'Firefox-Non-PGO', 09:07:42 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:42 INFO - 'config_files': ('talos/linux_config.py',), 09:07:42 INFO - 'default_actions': ('clobber', 09:07:42 INFO - 'read-buildbot-config', 09:07:42 INFO - 'download-and-extract', 09:07:42 INFO - 'populate-webroot', 09:07:42 INFO - 'create-virtualenv', 09:07:42 INFO - 'install', 09:07:42 INFO - 'run-tests'), 09:07:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:42 INFO - 'download_minidump_stackwalk': True, 09:07:42 INFO - 'download_symbols': 'ondemand', 09:07:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:42 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:42 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:42 INFO - 'installer_path': 'installer.exe', 09:07:42 INFO - 'log_level': 'info', 09:07:42 INFO - 'log_name': 'talos', 09:07:42 INFO - 'log_to_console': True, 09:07:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:07:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:07:42 INFO - 'opt_config_files': (), 09:07:42 INFO - 'pip_index': False, 09:07:42 INFO - 'sps_profile': False, 09:07:42 INFO - 'sps_profile_interval': 0, 09:07:42 INFO - 'suite': 'dromaeojs', 09:07:42 INFO - 'system_bits': '32', 09:07:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:42 INFO - 'title': 'talos-linux64-ix-025', 09:07:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:42 INFO - 'use_talos_json': True, 09:07:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:42 INFO - 'work_dir': 'build'} 09:07:42 INFO - [mozharness: 2017-01-07 17:07:42.733824Z] Running clobber step. 09:07:42 INFO - Running pre-action listener: _resource_record_pre_action 09:07:42 INFO - Running main action method: clobber 09:07:42 INFO - rmtree: /builds/slave/test/build 09:07:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:43 INFO - Running post-action listener: _resource_record_post_action 09:07:43 INFO - [mozharness: 2017-01-07 17:07:43.702782Z] Finished clobber step (success) 09:07:43 INFO - [mozharness: 2017-01-07 17:07:43.702881Z] Running read-buildbot-config step. 09:07:43 INFO - Running pre-action listener: _resource_record_pre_action 09:07:43 INFO - Running main action method: read_buildbot_config 09:07:43 INFO - Using buildbot properties: 09:07:43 INFO - { 09:07:43 INFO - "project": "", 09:07:43 INFO - "product": "firefox", 09:07:43 INFO - "who": "ryanvm@gmail.com", 09:07:43 INFO - "installer_path": "public/build/target.tar.bz2", 09:07:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:07:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:07:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 09:07:43 INFO - "stage_platform": "linux64", 09:07:43 INFO - "basedir": "/builds/slave/test", 09:07:43 INFO - "buildnumber": 373, 09:07:43 INFO - "platform": "ubuntu64_hw", 09:07:43 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:07:43 INFO - "slavebuilddir": "test", 09:07:43 INFO - "taskId": "f5Vr59zKSEiHWtmqYktQGA", 09:07:43 INFO - "branch": "mozilla-central", 09:07:43 INFO - "script_repo_revision": "production", 09:07:43 INFO - "revision": "e9d16569a7b4518e3a90a0512128b838118dea55", 09:07:43 INFO - "slavename": "talos-linux64-ix-025", 09:07:43 INFO - "repo_path": "mozilla-central" 09:07:43 INFO - } 09:07:43 INFO - Finding installer, test and symbols from parent task. 09:07:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5Vr59zKSEiHWtmqYktQGA'}, attempt #1 09:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5Vr59zKSEiHWtmqYktQGA'}, attempt #1 09:07:44 INFO - Task dependencies: CI46j_zNRhGjXNKIHRr94g 09:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g'}, attempt #1 09:07:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2 09:07:45 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/test_packages.json 09:07:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.crashreporter-symbols.zip 09:07:45 INFO - Running post-action listener: _resource_record_post_action 09:07:45 INFO - [mozharness: 2017-01-07 17:07:45.086809Z] Finished read-buildbot-config step (success) 09:07:45 INFO - [mozharness: 2017-01-07 17:07:45.087028Z] Running download-and-extract step. 09:07:45 INFO - Running pre-action listener: _resource_record_pre_action 09:07:45 INFO - Running main action method: download_and_extract 09:07:45 INFO - mkdir: /builds/slave/test/build/tests 09:07:45 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')]} 09:07:45 INFO - trying https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.test_packages.json 09:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:07:45 INFO - Downloaded 896 bytes. 09:07:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:07:45 INFO - Using the following test package requirements: 09:07:45 INFO - {u'common': [u'target.common.tests.zip'], 09:07:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:07:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:07:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:07:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:07:45 INFO - u'mozbase': [u'target.common.tests.zip'], 09:07:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:07:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:07:45 INFO - u'web-platform': [u'target.common.tests.zip', 09:07:45 INFO - u'target.web-platform.tests.zip'], 09:07:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:07:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:07:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.common.tests.zip 09:07:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:07:45 INFO - Fetch https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.common.tests.zip into memory 09:07:46 INFO - Expected file size: 20251356 09:07:46 INFO - Obtained file size: 20251356 09:07:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:07:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.common.tests.zip 09:07:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:07:50 INFO - Fetch https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.common.tests.zip into memory 09:07:51 INFO - Expected file size: 20251356 09:07:51 INFO - Obtained file size: 20251356 09:07:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.talos.tests.zip 09:07:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:07:55 INFO - Fetch https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.talos.tests.zip into memory 09:07:56 INFO - Expected file size: 14403537 09:07:56 INFO - Obtained file size: 14403537 09:07:56 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')]} 09:07:56 INFO - trying https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2 09:07:56 INFO - Downloading https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:07:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:07:57 INFO - Downloaded 55867247 bytes. 09:07:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2 09:07:57 INFO - mkdir: /builds/slave/test/properties 09:07:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:57 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:57 INFO - Contents: 09:07:57 INFO - build_url:https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2 09:07:57 INFO - Running post-action listener: _resource_record_post_action 09:07:57 INFO - Running post-action listener: set_extra_try_arguments 09:07:57 INFO - [mozharness: 2017-01-07 17:07:57.719172Z] Finished download-and-extract step (success) 09:07:57 INFO - [mozharness: 2017-01-07 17:07:57.719269Z] Running populate-webroot step. 09:07:57 INFO - Running pre-action listener: _resource_record_pre_action 09:07:57 INFO - Running main action method: populate_webroot 09:07:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:57 INFO - '--tppagecycles', 09:07:57 INFO - '7'], 09:07:57 INFO - 'tests': ['tsvgm']}}, 09:07:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['damp', 'tps']}, 09:07:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'tests': ['damp', 'tps']}, 09:07:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['dromaeo_dom']}, 09:07:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['basic_compositor_video']}, 09:07:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['a11yr', 09:07:57 INFO - 'ts_paint', 09:07:57 INFO - 'tpaint', 09:07:57 INFO - 'sessionrestore', 09:07:57 INFO - 'sessionrestore_no_auto_restore', 09:07:57 INFO - 'tabpaint']}, 09:07:57 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:57 INFO - 'ts_paint', 09:07:57 INFO - 'tpaint', 09:07:57 INFO - 'sessionrestore', 09:07:57 INFO - 'sessionrestore_no_auto_restore', 09:07:57 INFO - 'tabpaint']}, 09:07:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:57 INFO - 'ts_paint', 09:07:57 INFO - 'tpaint', 09:07:57 INFO - 'sessionrestore', 09:07:57 INFO - 'sessionrestore_no_auto_restore', 09:07:57 INFO - 'tabpaint']}, 09:07:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:57 INFO - 'ts_paint', 09:07:57 INFO - 'tpaint', 09:07:57 INFO - 'sessionrestore', 09:07:57 INFO - 'sessionrestore_no_auto_restore', 09:07:57 INFO - 'tabpaint']}, 09:07:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['a11yr', 09:07:57 INFO - 'ts_paint', 09:07:57 INFO - 'tpaint', 09:07:57 INFO - 'sessionrestore', 09:07:57 INFO - 'sessionrestore_no_auto_restore', 09:07:57 INFO - 'tabpaint']}, 09:07:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['a11yr', 09:07:57 INFO - 'ts_paint', 09:07:57 INFO - 'tpaint', 09:07:57 INFO - 'sessionrestore', 09:07:57 INFO - 'sessionrestore_no_auto_restore', 09:07:57 INFO - 'tabpaint']}, 09:07:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['tsvgx', 09:07:57 INFO - 'tsvgr_opacity', 09:07:57 INFO - 'tart', 09:07:57 INFO - 'tscrollx', 09:07:57 INFO - 'cart', 09:07:57 INFO - 'tsvg_static']}, 09:07:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:57 INFO - 'tsvgr_opacity', 09:07:57 INFO - 'tart', 09:07:57 INFO - 'tscrollx', 09:07:57 INFO - 'cart', 09:07:57 INFO - 'tsvg_static']}, 09:07:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'talos_options': ['--disable-e10s'], 09:07:57 INFO - 'tests': ['tp5o']}, 09:07:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:07:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'talos_options': ['--disable-e10s', 09:07:57 INFO - '--xperf_path', 09:07:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:57 INFO - 'tests': ['tp5n']}, 09:07:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:07:57 INFO - 'talos_options': ['--xperf_path', 09:07:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:57 INFO - 'tests': ['tp5n']}}, 09:07:57 INFO - 'talos.zip': {'path': '', 09:07:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:57 INFO - Running post-action listener: _resource_record_post_action 09:07:57 INFO - [mozharness: 2017-01-07 17:07:57.732241Z] Finished populate-webroot step (success) 09:07:57 INFO - [mozharness: 2017-01-07 17:07:57.732333Z] Running create-virtualenv step. 09:07:57 INFO - Running pre-action listener: _resource_record_pre_action 09:07:57 INFO - Running main action method: create_virtualenv 09:07:57 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:57 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 09:07:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:57 INFO - Using real prefix '/usr' 09:07:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:58 INFO - Installing distribute.............................................................................................................................................................................................done. 09:08:00 INFO - Installing pip.................done. 09:08:00 INFO - Return code: 0 09:08:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:08:00 INFO - Reading from file tmpfile_stdout 09:08:00 INFO - Output received: 09:08:00 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) 09:08:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:08:00 INFO - Using env: {'DISPLAY': ':0', 09:08:00 INFO - 'HOME': '/home/cltbld', 09:08:00 INFO - 'LANG': 'en_US.UTF-8', 09:08:00 INFO - 'LANGUAGE': 'en_US:en', 09:08:00 INFO - 'LOGNAME': 'cltbld', 09:08:00 INFO - 'MAIL': '/var/mail/cltbld', 09:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:00 INFO - 'MOZ_NO_REMOTE': '1', 09:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:00 INFO - 'NO_EM_RESTART': '1', 09:08:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:00 INFO - 'PWD': '/builds/slave/test', 09:08:00 INFO - 'SHELL': '/bin/bash', 09:08:00 INFO - 'SHLVL': '1', 09:08:00 INFO - 'TERM': 'linux', 09:08:00 INFO - 'TMOUT': '86400', 09:08:00 INFO - 'USER': 'cltbld', 09:08:00 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:00 INFO - '_': '/tools/buildbot/bin/python'} 09:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:00 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 09:08:00 INFO - Cleaning up... 09:08:00 INFO - Return code: 0 09:08:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:08:00 INFO - Using env: {'DISPLAY': ':0', 09:08:00 INFO - 'HOME': '/home/cltbld', 09:08:00 INFO - 'LANG': 'en_US.UTF-8', 09:08:00 INFO - 'LANGUAGE': 'en_US:en', 09:08:00 INFO - 'LOGNAME': 'cltbld', 09:08:00 INFO - 'MAIL': '/var/mail/cltbld', 09:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:00 INFO - 'MOZ_NO_REMOTE': '1', 09:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:00 INFO - 'NO_EM_RESTART': '1', 09:08:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:00 INFO - 'PWD': '/builds/slave/test', 09:08:00 INFO - 'SHELL': '/bin/bash', 09:08:00 INFO - 'SHLVL': '1', 09:08:00 INFO - 'TERM': 'linux', 09:08:00 INFO - 'TMOUT': '86400', 09:08:00 INFO - 'USER': 'cltbld', 09:08:00 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:00 INFO - '_': '/tools/buildbot/bin/python'} 09:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:00 INFO - Downloading/unpacking psutil>=3.1.1 09:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:03 INFO - Installing collected packages: psutil 09:08:03 INFO - Running setup.py install for psutil 09:08:03 INFO - building 'psutil._psutil_linux' extension 09:08:03 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 09:08:03 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 09:08:03 INFO - building 'psutil._psutil_posix' extension 09:08:03 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 09:08:03 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 09:08:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:03 INFO - Successfully installed psutil 09:08:03 INFO - Cleaning up... 09:08:03 INFO - Return code: 0 09:08:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:08:03 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')]} 09:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:03 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')]} 09:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:03 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:03 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 09:08:03 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 09:08:03 INFO - Using env: {'DISPLAY': ':0', 09:08:03 INFO - 'HOME': '/home/cltbld', 09:08:03 INFO - 'LANG': 'en_US.UTF-8', 09:08:03 INFO - 'LANGUAGE': 'en_US:en', 09:08:03 INFO - 'LOGNAME': 'cltbld', 09:08:03 INFO - 'MAIL': '/var/mail/cltbld', 09:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:03 INFO - 'MOZ_NO_REMOTE': '1', 09:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:03 INFO - 'NO_EM_RESTART': '1', 09:08:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:03 INFO - 'PWD': '/builds/slave/test', 09:08:03 INFO - 'SHELL': '/bin/bash', 09:08:03 INFO - 'SHLVL': '1', 09:08:03 INFO - 'TERM': 'linux', 09:08:03 INFO - 'TMOUT': '86400', 09:08:03 INFO - 'USER': 'cltbld', 09:08:03 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:03 INFO - '_': '/tools/buildbot/bin/python'} 09:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:08:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:08:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:08:05 INFO - Installing collected packages: mozsystemmonitor 09:08:05 INFO - Running setup.py install for mozsystemmonitor 09:08:06 INFO - Successfully installed mozsystemmonitor 09:08:06 INFO - Cleaning up... 09:08:06 INFO - Return code: 0 09:08:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:06 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:06 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 09:08:06 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 09:08:06 INFO - Using env: {'DISPLAY': ':0', 09:08:06 INFO - 'HOME': '/home/cltbld', 09:08:06 INFO - 'LANG': 'en_US.UTF-8', 09:08:06 INFO - 'LANGUAGE': 'en_US:en', 09:08:06 INFO - 'LOGNAME': 'cltbld', 09:08:06 INFO - 'MAIL': '/var/mail/cltbld', 09:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:06 INFO - 'MOZ_NO_REMOTE': '1', 09:08:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:06 INFO - 'NO_EM_RESTART': '1', 09:08:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:06 INFO - 'PWD': '/builds/slave/test', 09:08:06 INFO - 'SHELL': '/bin/bash', 09:08:06 INFO - 'SHLVL': '1', 09:08:06 INFO - 'TERM': 'linux', 09:08:06 INFO - 'TMOUT': '86400', 09:08:06 INFO - 'USER': 'cltbld', 09:08:06 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:06 INFO - '_': '/tools/buildbot/bin/python'} 09:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:08:06 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 09:08:06 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 09:08:06 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 09:08:06 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 09:08:06 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 09:08:06 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 09:08:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:08:08 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 09:08:08 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 09:08:08 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 09:08:08 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 09:08:08 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 09:08:08 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 09:08:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:08 INFO - warning: no files found matching '*.txt' 09:08:08 INFO - no previously-included directories found matching 'build' 09:08:08 INFO - no previously-included directories found matching 'dist' 09:08:08 INFO - no previously-included directories found matching '.git*' 09:08:08 INFO - Installing collected packages: jsonschema, functools32 09:08:08 INFO - Running setup.py install for functools32 09:08:08 INFO - warning: no files found matching '*.txt' 09:08:08 INFO - no previously-included directories found matching 'build' 09:08:08 INFO - no previously-included directories found matching 'dist' 09:08:08 INFO - no previously-included directories found matching '.git*' 09:08:08 INFO - Successfully installed jsonschema functools32 09:08:08 INFO - Cleaning up... 09:08:08 INFO - Return code: 0 09:08:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:08 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:08 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 09:08:08 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 09:08:08 INFO - Using env: {'DISPLAY': ':0', 09:08:08 INFO - 'HOME': '/home/cltbld', 09:08:08 INFO - 'LANG': 'en_US.UTF-8', 09:08:08 INFO - 'LANGUAGE': 'en_US:en', 09:08:08 INFO - 'LOGNAME': 'cltbld', 09:08:08 INFO - 'MAIL': '/var/mail/cltbld', 09:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:08 INFO - 'MOZ_NO_REMOTE': '1', 09:08:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:08 INFO - 'NO_EM_RESTART': '1', 09:08:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:08 INFO - 'PWD': '/builds/slave/test', 09:08:08 INFO - 'SHELL': '/bin/bash', 09:08:08 INFO - 'SHLVL': '1', 09:08:08 INFO - 'TERM': 'linux', 09:08:08 INFO - 'TMOUT': '86400', 09:08:08 INFO - 'USER': 'cltbld', 09:08:08 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:08 INFO - '_': '/tools/buildbot/bin/python'} 09:08:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:08:08 INFO - Cleaning up... 09:08:09 INFO - Return code: 0 09:08:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:08:09 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')]} 09:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:09 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')]} 09:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:09 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:09 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 09:08:09 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 09:08:09 INFO - Using env: {'DISPLAY': ':0', 09:08:09 INFO - 'HOME': '/home/cltbld', 09:08:09 INFO - 'LANG': 'en_US.UTF-8', 09:08:09 INFO - 'LANGUAGE': 'en_US:en', 09:08:09 INFO - 'LOGNAME': 'cltbld', 09:08:09 INFO - 'MAIL': '/var/mail/cltbld', 09:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:09 INFO - 'MOZ_NO_REMOTE': '1', 09:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:09 INFO - 'NO_EM_RESTART': '1', 09:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:09 INFO - 'PWD': '/builds/slave/test', 09:08:09 INFO - 'SHELL': '/bin/bash', 09:08:09 INFO - 'SHLVL': '1', 09:08:09 INFO - 'TERM': 'linux', 09:08:09 INFO - 'TMOUT': '86400', 09:08:09 INFO - 'USER': 'cltbld', 09:08:09 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:09 INFO - '_': '/tools/buildbot/bin/python'} 09:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:08:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:08:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 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 09:08:11 INFO - Downloading docopt-0.6.1.tar.gz 09:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:08:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:08:12 INFO - Running setup.py install for blobuploader 09:08:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:08:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:08:12 INFO - Running setup.py install for requests 09:08:12 INFO - Running setup.py install for docopt 09:08:12 INFO - Successfully installed blobuploader requests docopt 09:08:12 INFO - Cleaning up... 09:08:12 INFO - Return code: 0 09:08:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:12 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:12 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 09:08:12 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 09:08:12 INFO - Using env: {'DISPLAY': ':0', 09:08:12 INFO - 'HOME': '/home/cltbld', 09:08:12 INFO - 'LANG': 'en_US.UTF-8', 09:08:12 INFO - 'LANGUAGE': 'en_US:en', 09:08:12 INFO - 'LOGNAME': 'cltbld', 09:08:12 INFO - 'MAIL': '/var/mail/cltbld', 09:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:12 INFO - 'MOZ_NO_REMOTE': '1', 09:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:12 INFO - 'NO_EM_RESTART': '1', 09:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:12 INFO - 'PWD': '/builds/slave/test', 09:08:12 INFO - 'SHELL': '/bin/bash', 09:08:12 INFO - 'SHLVL': '1', 09:08:12 INFO - 'TERM': 'linux', 09:08:12 INFO - 'TMOUT': '86400', 09:08:12 INFO - 'USER': 'cltbld', 09:08:12 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:12 INFO - '_': '/tools/buildbot/bin/python'} 09:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:12 INFO - Running setup.py (path:/tmp/pip-kEOxbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:12 INFO - Running setup.py (path:/tmp/pip-FC2LET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:12 INFO - Running setup.py (path:/tmp/pip-JBNnyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:13 INFO - Running setup.py (path:/tmp/pip-xgL0C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:13 INFO - Running setup.py (path:/tmp/pip-VOH0H3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:13 INFO - Running setup.py (path:/tmp/pip-xiPUUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:13 INFO - Running setup.py (path:/tmp/pip-OqOjkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:13 INFO - Running setup.py (path:/tmp/pip-8Kuplv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:13 INFO - Running setup.py (path:/tmp/pip-WULfU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:13 INFO - Running setup.py (path:/tmp/pip-ImlXPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:13 INFO - Running setup.py (path:/tmp/pip-v0zz6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:13 INFO - Running setup.py (path:/tmp/pip-ThS9KP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:13 INFO - Running setup.py (path:/tmp/pip-DPe1Bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:13 INFO - Running setup.py (path:/tmp/pip-TNxx7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:13 INFO - Running setup.py (path:/tmp/pip-9d5_Ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:13 INFO - Running setup.py (path:/tmp/pip-ImFAKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:14 INFO - Running setup.py (path:/tmp/pip-Jrooky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:14 INFO - Running setup.py install for manifestparser 09:08:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Running setup.py install for mozcrash 09:08:14 INFO - Running setup.py install for mozdebug 09:08:14 INFO - Running setup.py install for mozdevice 09:08:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Running setup.py install for mozfile 09:08:14 INFO - Running setup.py install for mozhttpd 09:08:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Running setup.py install for mozinfo 09:08:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Running setup.py install for mozInstall 09:08:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:14 INFO - Running setup.py install for mozleak 09:08:14 INFO - Running setup.py install for mozlog 09:08:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Running setup.py install for moznetwork 09:08:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Running setup.py install for mozprocess 09:08:15 INFO - Running setup.py install for mozprofile 09:08:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Running setup.py install for mozrunner 09:08:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Running setup.py install for mozscreenshot 09:08:15 INFO - Running setup.py install for moztest 09:08:15 INFO - Running setup.py install for mozversion 09:08:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:15 INFO - Cleaning up... 09:08:15 INFO - Return code: 0 09:08:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:15 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:15 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 09:08:15 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 09:08:15 INFO - Using env: {'DISPLAY': ':0', 09:08:15 INFO - 'HOME': '/home/cltbld', 09:08:15 INFO - 'LANG': 'en_US.UTF-8', 09:08:15 INFO - 'LANGUAGE': 'en_US:en', 09:08:15 INFO - 'LOGNAME': 'cltbld', 09:08:15 INFO - 'MAIL': '/var/mail/cltbld', 09:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:15 INFO - 'MOZ_NO_REMOTE': '1', 09:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:15 INFO - 'NO_EM_RESTART': '1', 09:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:15 INFO - 'PWD': '/builds/slave/test', 09:08:15 INFO - 'SHELL': '/bin/bash', 09:08:15 INFO - 'SHLVL': '1', 09:08:15 INFO - 'TERM': 'linux', 09:08:15 INFO - 'TMOUT': '86400', 09:08:15 INFO - 'USER': 'cltbld', 09:08:15 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:15 INFO - '_': '/tools/buildbot/bin/python'} 09:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:16 INFO - Running setup.py (path:/tmp/pip-geo1jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:16 INFO - Running setup.py (path:/tmp/pip-Ng3jO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:16 INFO - Running setup.py (path:/tmp/pip-kgH3Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:16 INFO - Running setup.py (path:/tmp/pip-HliLmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:16 INFO - Running setup.py (path:/tmp/pip-S0ze18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:16 INFO - Running setup.py (path:/tmp/pip-FQoXyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:16 INFO - Running setup.py (path:/tmp/pip-uHL_Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:16 INFO - Running setup.py (path:/tmp/pip-7rgLgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:16 INFO - Running setup.py (path:/tmp/pip-wgASbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:16 INFO - Running setup.py (path:/tmp/pip-BKw0mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:16 INFO - Running setup.py (path:/tmp/pip-IgkUF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:16 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:16 INFO - Running setup.py (path:/tmp/pip-_uPZZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 09:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:16 INFO - Running setup.py (path:/tmp/pip-2YAwEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:17 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)) 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:17 INFO - Running setup.py (path:/tmp/pip-Hirtad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:17 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)) 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:17 INFO - Running setup.py (path:/tmp/pip-9MVk85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:17 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)) 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:17 INFO - Running setup.py (path:/tmp/pip-7dCT_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:17 INFO - Running setup.py (path:/tmp/pip-gNbKei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:17 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)) 09:08:17 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)) 09:08:17 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)) 09:08:17 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)) 09:08:17 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)) 09:08:17 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)) 09:08:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 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 09:08:17 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 09:08:19 INFO - Downloading blessings-1.6.tar.gz 09:08:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:19 INFO - Installing collected packages: blessings 09:08:19 INFO - Running setup.py install for blessings 09:08:19 INFO - Successfully installed blessings 09:08:19 INFO - Cleaning up... 09:08:19 INFO - Return code: 0 09:08:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:19 INFO - Reading from file tmpfile_stdout 09:08:19 INFO - Current package versions: 09:08:19 INFO - argparse == 1.2.1 09:08:19 INFO - blessings == 1.6 09:08:19 INFO - blobuploader == 1.2.4 09:08:19 INFO - docopt == 0.6.1 09:08:19 INFO - functools32 == 3.2.3-2 09:08:19 INFO - jsonschema == 2.5.1 09:08:19 INFO - manifestparser == 1.1 09:08:19 INFO - mozInstall == 1.12 09:08:19 INFO - mozcrash == 1.0 09:08:19 INFO - mozdebug == 0.1 09:08:19 INFO - mozdevice == 0.49 09:08:19 INFO - mozfile == 1.2 09:08:19 INFO - mozhttpd == 0.7 09:08:19 INFO - mozinfo == 0.9 09:08:19 INFO - mozleak == 0.1 09:08:19 INFO - mozlog == 3.4 09:08:19 INFO - moznetwork == 0.27 09:08:19 INFO - mozprocess == 0.24 09:08:19 INFO - mozprofile == 0.28 09:08:19 INFO - mozrunner == 6.13 09:08:19 INFO - mozscreenshot == 0.1 09:08:19 INFO - mozsystemmonitor == 0.3 09:08:19 INFO - moztest == 0.8 09:08:19 INFO - mozversion == 1.4 09:08:19 INFO - psutil == 3.1.1 09:08:19 INFO - requests == 1.2.3 09:08:19 INFO - wsgiref == 0.1.2 09:08:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:19 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')]} 09:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:19 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')]} 09:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:19 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:19 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 09:08:19 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 09:08:19 INFO - Using env: {'DISPLAY': ':0', 09:08:19 INFO - 'HOME': '/home/cltbld', 09:08:19 INFO - 'LANG': 'en_US.UTF-8', 09:08:19 INFO - 'LANGUAGE': 'en_US:en', 09:08:19 INFO - 'LOGNAME': 'cltbld', 09:08:19 INFO - 'MAIL': '/var/mail/cltbld', 09:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:19 INFO - 'MOZ_NO_REMOTE': '1', 09:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:19 INFO - 'NO_EM_RESTART': '1', 09:08:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:19 INFO - 'PWD': '/builds/slave/test', 09:08:19 INFO - 'SHELL': '/bin/bash', 09:08:19 INFO - 'SHLVL': '1', 09:08:19 INFO - 'TERM': 'linux', 09:08:19 INFO - 'TMOUT': '86400', 09:08:19 INFO - 'USER': 'cltbld', 09:08:19 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:19 INFO - '_': '/tools/buildbot/bin/python'} 09:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 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)) 09:08:20 INFO - Cleaning up... 09:08:20 INFO - Return code: 0 09:08:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08:20 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')]} 09:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:20 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')]} 09:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:20 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 0xad81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xbe8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe4a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, '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': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', '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 09:08:20 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 09:08:20 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 09:08:20 INFO - Using env: {'DISPLAY': ':0', 09:08:20 INFO - 'HOME': '/home/cltbld', 09:08:20 INFO - 'LANG': 'en_US.UTF-8', 09:08:20 INFO - 'LANGUAGE': 'en_US:en', 09:08:20 INFO - 'LOGNAME': 'cltbld', 09:08:20 INFO - 'MAIL': '/var/mail/cltbld', 09:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:20 INFO - 'MOZ_NO_REMOTE': '1', 09:08:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:20 INFO - 'NO_EM_RESTART': '1', 09:08:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:20 INFO - 'PWD': '/builds/slave/test', 09:08:20 INFO - 'SHELL': '/bin/bash', 09:08:20 INFO - 'SHLVL': '1', 09:08:20 INFO - 'TERM': 'linux', 09:08:20 INFO - 'TMOUT': '86400', 09:08:20 INFO - 'USER': 'cltbld', 09:08:20 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:20 INFO - '_': '/tools/buildbot/bin/python'} 09:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:08:20 INFO - Cleaning up... 09:08:20 INFO - Return code: 0 09:08:20 INFO - Running post-action listener: _resource_record_post_action 09:08:20 INFO - Running post-action listener: _start_resource_monitoring 09:08:20 INFO - Starting resource monitoring. 09:08:20 INFO - [mozharness: 2017-01-07 17:08:20.310250Z] Finished create-virtualenv step (success) 09:08:20 INFO - [mozharness: 2017-01-07 17:08:20.310704Z] Running install step. 09:08:20 INFO - Running pre-action listener: _resource_record_pre_action 09:08:20 INFO - Running main action method: install 09:08:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:20 INFO - Reading from file tmpfile_stdout 09:08:20 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:20 INFO - Reading from file tmpfile_stdout 09:08:20 INFO - Output received: 09:08:20 INFO - Usage: mozinstall [options] installer 09:08:20 INFO - Options: 09:08:20 INFO - -h, --help show this help message and exit 09:08:20 INFO - -d DEST, --destination=DEST 09:08:20 INFO - Directory to install application into. [default: 09:08:20 INFO - "/builds/slave/test"] 09:08:20 INFO - --app=APP Application being installed. [default: firefox] 09:08:20 INFO - mkdir: /builds/slave/test/build/application 09:08:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:08:36 INFO - Reading from file tmpfile_stdout 09:08:36 INFO - Output received: 09:08:36 INFO - /builds/slave/test/build/application/firefox/firefox 09:08:36 INFO - Running post-action listener: _resource_record_post_action 09:08:36 INFO - [mozharness: 2017-01-07 17:08:36.844590Z] Finished install step (success) 09:08:36 INFO - [mozharness: 2017-01-07 17:08:36.844942Z] Running run-tests step. 09:08:36 INFO - Running pre-action listener: _resource_record_pre_action 09:08:36 INFO - Running main action method: run_tests 09:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:36 INFO - Python 2.7.3 09:08:36 INFO - Return code: 0 09:08:36 INFO - grabbing minidump binary from tooltool 09:08:36 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')]} 09:08:36 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 0xed3850>, '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 0xe486d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe48b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:36 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 09:08:36 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 09:08:36 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 09:08:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:36 INFO - Return code: 0 09:08:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:08:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:08:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 09:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-025 --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 09:08:36 INFO - Using env: {'DISPLAY': ':0', 09:08:36 INFO - 'HOME': '/home/cltbld', 09:08:36 INFO - 'LANG': 'en_US.UTF-8', 09:08:36 INFO - 'LANGUAGE': 'en_US:en', 09:08:36 INFO - 'LOGNAME': 'cltbld', 09:08:36 INFO - 'MAIL': '/var/mail/cltbld', 09:08:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:36 INFO - 'MOZ_NO_REMOTE': '1', 09:08:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:36 INFO - 'NO_EM_RESTART': '1', 09:08:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:36 INFO - 'PWD': '/builds/slave/test', 09:08:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:36 INFO - 'SHELL': '/bin/bash', 09:08:36 INFO - 'SHLVL': '1', 09:08:36 INFO - 'TERM': 'linux', 09:08:36 INFO - 'TMOUT': '86400', 09:08:36 INFO - 'USER': 'cltbld', 09:08:36 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1483808861.122813-290819486', 09:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:36 INFO - '_': '/tools/buildbot/bin/python'} 09:08:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--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 09:08:37 INFO - mozversion application_buildid: 20170107162259 09:08:37 INFO - mozversion application_changeset: e9d16569a7b4518e3a90a0512128b838118dea55 09:08:37 INFO - mozversion application_display_name: Nightly 09:08:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:37 INFO - mozversion application_name: Firefox 09:08:37 INFO - mozversion application_remotingname: firefox 09:08:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:08:37 INFO - mozversion application_vendor: Mozilla 09:08:37 INFO - mozversion application_version: 53.0a1 09:08:37 INFO - mozversion platform_buildid: 20170107162259 09:08:37 INFO - mozversion platform_changeset: e9d16569a7b4518e3a90a0512128b838118dea55 09:08:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:08:37 INFO - mozversion platform_version: 53.0a1 09:08:37 INFO - using testdate: 1483808917 09:08:37 INFO - actual date: 1483808917 09:08:37 INFO - starting webserver on 'localhost:38805' 09:08:37 INFO - SUITE-START | Running 2 tests 09:08:37 INFO - TEST-START | dromaeo_css 09:08:37 INFO - operating with platform_type : linux_ 09:08:37 INFO - Initialising browser for dromaeo_css test... 09:08:37 INFO - TEST-INFO | started process 9301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsPwV3D/profile http://localhost:38805/getInfo.html) 09:08:37 INFO - PROCESS | 9301 | 1483808917647 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:08:41 INFO - PROCESS | 9301 | __metrics Screen width/height:1600/1200 09:08:41 INFO - PROCESS | 9301 | colorDepth:24 09:08:41 INFO - PROCESS | 9301 | Browser inner width/height: 1024/697 09:08:41 INFO - PROCESS | 9301 | __metrics 09:08:41 INFO - PROCESS | 9301 | 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" 09:08:41 INFO - PROCESS | 9301 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:42 INFO - PROCESS | 9301 | console.error: PushService: 09:08:42 INFO - PROCESS | 9301 | stateChangeProcessEnqueue: Error transitioning state 09:08:42 INFO - PROCESS | 9301 | UnknownError 09:08:43 INFO - TEST-INFO | 9301: exit 0 09:08:43 INFO - Browser initialized. 09:08:43 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:43 INFO - TEST-INFO | started process 9383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsPwV3D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:55 INFO - PROCESS | 9383 | RSS: Main: 151969792 09:08:55 INFO - PROCESS | 9383 | 09:12:00 INFO - PROCESS | 9383 | Cycle 1(1): loaded http://localhost:38805/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38805/tests/dromaeo/cssquery-ext.html) 09:12:01 INFO - PROCESS | 9383 | RSS: Main: 258220032 09:12:01 INFO - PROCESS | 9383 | 09:13:44 INFO - PROCESS | 9383 | ************************* 09:13:44 INFO - PROCESS | 9383 | A coding exception was thrown and uncaught in a Task. 09:13:44 INFO - PROCESS | 9383 | 09:13:44 INFO - PROCESS | 9383 | Full message: ReferenceError: fetch is not defined 09:13:44 INFO - PROCESS | 9383 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:13:44 INFO - PROCESS | 9383 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:13:44 INFO - PROCESS | 9383 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:13:44 INFO - PROCESS | 9383 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:13:44 INFO - PROCESS | 9383 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:13:44 INFO - PROCESS | 9383 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:13:44 INFO - PROCESS | 9383 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:13:44 INFO - PROCESS | 9383 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:13:44 INFO - PROCESS | 9383 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:13:44 INFO - PROCESS | 9383 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:13:44 INFO - PROCESS | 9383 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:13:44 INFO - PROCESS | 9383 | 09:13:44 INFO - PROCESS | 9383 | ************************* 09:15:04 INFO - PROCESS | 9383 | Cycle 1(1): loaded http://localhost:38805/tests/dromaeo/cssquery-ext.html (next: http://localhost:38805/tests/dromaeo/cssquery-jquery.html) 09:15:04 INFO - PROCESS | 9383 | RSS: Main: 233480192 09:15:04 INFO - PROCESS | 9383 | 09:18:07 INFO - PROCESS | 9383 | Cycle 1(1): loaded http://localhost:38805/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38805/tests/dromaeo/cssquery-mootools.html) 09:18:07 INFO - PROCESS | 9383 | RSS: Main: 187625472 09:18:07 INFO - PROCESS | 9383 | 09:21:10 INFO - PROCESS | 9383 | Cycle 1(1): loaded http://localhost:38805/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38805/tests/dromaeo/cssquery-prototype.html) 09:21:10 INFO - PROCESS | 9383 | RSS: Main: 194973696 09:21:10 INFO - PROCESS | 9383 | 09:24:13 INFO - PROCESS | 9383 | Cycle 1(1): loaded http://localhost:38805/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38805/tests/dromaeo/cssquery-yui.html) 09:24:14 INFO - PROCESS | 9383 | RSS: Main: 184672256 09:24:14 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | Cycle 1(1): loaded http://localhost:38805/tests/dromaeo/cssquery-yui.html (next: http://localhost:38805/tests/dromaeo/cssquery-dojo.html) 09:27:17 INFO - PROCESS | 9383 | RSS: Main: 288448512 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | __start_tp_report 09:27:17 INFO - PROCESS | 9383 | _x_x_mozilla_page_load 09:27:17 INFO - PROCESS | 9383 | _x_x_mozilla_page_load_details 09:27:17 INFO - PROCESS | 9383 | |i|pagename|runs| 09:27:17 INFO - PROCESS | 9383 | |0;dojo.html;2596.831205971612;2608.5435048866443;2623.2523730736743;2635.1304069657017;2635.341164708823;4731.124741922738;4766.73782941938;4784.234522476405;4823.276508523723;4851.102546028978;4774.7851346689395;4825.083234185511;4831.8792030199265;4832.395950506187;4870.269459581055;4408.669349798762;4438.3342498625225;4451.332300154986;4460.598546130847;4542.341360502725;4530.07133537624;4534.705244159136;4536.659750518707;4575.794089265991;4617.838375656853;4400.581944715279;4440.600345968878;4442.2892337225885;4508.1659892919915;4546.249868771649;4397.208502469554;4441.200583894898;4458.509563947963;4474.7762611869275;4532.864014079583;4423.933640995388;4435.733855968652;4452.287633978548;4454.175977444151;4471.9776401118115;4722.220833562458;4790.8323208687525;4832.589229915427;4859.902801943941;4860.392450943632;5089.720065396402;5118.411382691001;5165.431802501722;5170.948290517122;5229.346331708536;5187.403448603422;5199.6360254782185;5210.895782084337;5210.921836172461;5215.947840521584;4831.057943700945;4838.709677419296;4841.1285968526;4846.854594362139;4881.780319885614;5183.248428977777;5247.685138891678;5251.711155886386;5308.0445519806035;5308.442613525602;5099.133147364957;5100;5102.4642412546145;5114.053900028507;5204.609654275869;5067.721275329893;5110.182370820651;5224.477552244821;5229.450907654642;5239.345081864767;5034.874128146753;5126.53861152498;5162.199859021857;5165.767540460688;5238.135707608279;5163.922541161885;5175.534201921845;5175.767090480862;5179.637425380187;5181.715005674724;4394.36281739146;4428.601425871687;4431.047324825178;4445.244308467568;4465.531119232499;3829.349010668193;3833.750806197588;3878.5927477614446;3878.825452854628;3890.3775395936514;4453.777311134495;4457.286834106528;4458.108378324283;4463.776811159429;4503.729776213438;5121.308623335876;5176.948230517647;5225.477452254744;5234.338376836454;5289.70906600133;4393.604575588212;4420.336949457607;4422.579854913778;4446.155230506193;4487.371767952489;4677.859664210079;4683.414573178353;4685.789139488663;4687.156311863897;4737.8104875805275;4786.784594693317;4827.927581086356;4844;4844.806207751721;4882.4629290777275;5165;5202.297689811827;5220.6867587943325;5222.29499017643;5254.579633629377;4851.660383773102;4861.829835955725;4870.78081486341;4873.707577545359;4917;4993.750312484434;5038.773255203451;5142;5145.305383773143;5151.587872970228;5081.212412076134;5134.358205224347;5149.253358262956;5183.455737147545;5239.109351410193;5019.84940451787;5029.371328583927;5033.094043072136;5034.723090230073;5080.745962701776;4917.877053073702;5013.774380152829;5015.147424937696;5015.448300686994;5039.3448851649055;4902.828401005948;4945.406551213878;4948.356713627206;4965.354503914468;5019.7741101651245;5080.491950805038;5081.034603425337;5081.8729531762;5086.821961231339;5136.486351364833;4848.660593758403;4945.802167913387;4948.2082866742585;4950.183219768698;4995.875103122451 09:27:17 INFO - PROCESS | 9383 | |1;ext.html;487.26423101117314;507.266999186176;512.2776884592722;516.9870753231169;527.3645257464756;28930.276743081406;39334.409983850266;41753;41832;45380;41790.58209417905;42234.788826055825;42273.78863105688;42328.36507452383;42340.36489452653;7605.315521603055;7620.542767433972;7835.451518393715;7837.647305871249;7847.6468558914985;7541.170471248186;9556.18772404343;9828.606855725762;9833.065858743408;10139.695809125738;10119.494025298742;10188.949055254714;10192.74518137045;10203.08172264496;10203.234757393226;10035;10083.344582602107;10102.141317987905;10102.14131798798;10104.545295461685;9869.654562090293;9926.80146397068;9941.850872236922;9944.204463642891;9946.90053099467;39643.40534891979;40014;40032.59967400318;40081.79959100186;40125.79937100325;3522.4011917974008;3656.8537258509627;3706.9814650926573;3712;3716.6097559756377;28090.578641320397;28535.286617834514;30247.243818904484;31092.222694432596;31834.522482162785;3356.2616224430585;3449.706360114957;3464.4456886897974;3498.5626796650417;3504.001359612498;240018;243083;244908;244979;245710.77144614342;59863.40136598578;60725.39274607197;60800.391996079474;60802.39197607967;60964.39035609676;64137.35862641407;64546.35453645497;64555.35444645587;64556.35443645597;64558.67720661461;12317.137800354069;12649.177803442746;12661.366931653565;12663.240205587694;12663.36683165838;8612.956935215283;8893;9003.234725048313;9225.261979041661;9393.859092113624;17916.28334866617;17985.820141798413;18017.297405389298;18040.548986275448;18625.627487450445;7366.263373662698;7518.323350898446;7622.237776222333;7633.160352361236;7694.576798276036;794.6225542867207;805.3557154276673;810.9310708589836;812.2080971053176;831.1563762780783;27423.86288068547;28581.714182857904;28812.711872881002;29132.563011554845;29393.11820645384;949.9145076943108;952.4713783849892;970.6997998865096;983.2379905573094;988.8961659025842;14488.710225795636;15303.923480382527;15459.536213913601;15669.686606267811;15716.449924252596;340588;344093;344361;344737;345106;50626.746866265436;51270.487295127314;51441;51441.742791285804;51584.74207628938;1214.769193853165;1246.1152581666936;1252.6680429685957;1262.280500114944;1268.1566758105757;25857.870710646326;26694.866525667247;27624.30939226455;27848.72151278542;27995.580066298615;44418.55581444144;44466.3330050043;44467;44470.777646111565;44923.77538112288;282524;282696;284797;285220;285554;33517;34339.65660343465;38137;38721;38924.41613375745;42094.78952605218;42707.35938960856;42718.815454677395;43154.56845431631;43660.345094822966;35641.46537801987;35918.28163436769;36070.27859442849;36170.819145904105;36855.8157209223;35509.28981420409;35605.465918010734;35967.64032359643;36096.27807443784;36181.638183617826;29676.509845678618;29906.401871962873;29911.401771964873;30120.24699382533;30204.24489387783;29647.11058668157;31627.683723162474;31961.200969975936;32284.515732263564;32329.353412931137;22082.558348833256;23457;24534.87732561326;24580.38549036217;24780.25659230226 09:27:17 INFO - PROCESS | 9383 | |2;jquery.html;345.31110434682796;353.65695275582675;377.5657993307696;378.0699479280969;391.3093390166357;13521.939016647013;13894.23581703006;18501.53746156347;18533.629327413477;18547.258109675597;17713;17874.910625446853;18013.63972720545;18239.908800455978;18258.087095645198;3957.366821308591;5003.17447621142;5040.949590504085;5067.974660126694;5140.126178549637;4032.9395059074136;4050.16971520839;4146.60607242313;4146.917061658765;5958.97020514899;5940.07928771039;6133.11069894865;6156.630602163862;6174.043023331368;6179.5674302798825;6065.514758819284;6100.60346077508;6110;6116.969415152918;6134.601250918676;5168.844934651929;5330.413654498002;5337.172738225581;5359;5428.728563571805;21865.453363666034;21900.561988760135;22024.889875550518;22093.447663808372;22151.33545993623;2254.8985295661732;2282.6233671444356;2302.3668491164894;2322.790948814598;2378.2389635316704;12863.614091577268;13130.409131589102;13166.341682915912;13442.529511467052;13503.662408439868;1724.7499112628725;1740.321274702867;1771.2737777511288;1776.2717285912713;1786.7319902014672;72370;72475.63762181209;73620.63189684018;74034.62982685106;75005.62497187534;9557.23542116629;9848.931724337826;9907.554160062815;9970.002999700117;10000.599976001024;13260.73478530424;13269.867301327056;13276.87569988797;13280.269585172908;13412.93293533526;6309.463695585927;6495.512836537278;6632.237292711304;6637.535372523877;6680.498962577826;5890.705464726747;5932.881342373128;6080.391960803884;6096.055111457731;6105.297890742621;6302.464290535356;6409.775657851953;6438.774642887476;6455.289918109004;6509.707063182168;1661.5264649574813;1729.3428497170994;1776.4936992956946;1785.7946336171506;1791.0984205587033;798.660569258063;805.1867613710195;819.8726750717763;822.0176888618164;823.9134890836496;17002;17271.91364043197;17441.30234790594;18036.63926721458;18109.909450452662;801.8075661841233;803.2449497472358;807.5800583696563;812.8293058457672;825.7976795685096;9856.31005829585;10413.739656508647;10441.530131144116;10456.11527926641;10555.366677999345;467364;478154;493252;494887;495148;58882;79275.60362198151;81352.59323703343;81542.59228703937;81717.59141204375;909.372532952274;935.1957316707626;957.1481381570408;958.1999030809213;963.4123184857232;15463.226838658247;15544.05699287589;15952.361905524112;16156;16301.836981630031;35755.46366804448;36786.8160659195;37087.6291237095;37129.443058354685;37194.62805371912;50363.74818125886;50612.493875060776;50661.74669126631;50786.746066269436;50913;7373.410127189919;7448.180700123091;7553.811154720956;7573.166951635426;7745.22547745221;11268.436578171222;12049.216800907912;12295.69260768488;12735.363231838557;12977.286249256382;9740.220782337254;9914;9941.701748947544;10078.848817267894;10652.147828173882;10619.681409557727;10939.179561532757;10994.120470362512;11084.390358530358;11364.147688923198;9943.701688949342;10243.07812296897;10255.948720256649;10539;10653.786924261316;7619.161892191743;7744.148143704076;7759.185285544936;7897.091834439113;7899.960500197462;7430.92569074324;9782.266330025419;9996.950015249877;10195.694129176136;10339.0694837465 09:27:17 INFO - PROCESS | 9383 | |3;mootools.html;1138.982915256271;1143.182624423537;1147.0880649883343;1148.8908553687397;1177.0289511153298;6884.3115688431135;7174.641267936595;7190.856182876352;7194.24460431655;7211.783646490607;6450.387213214749;6456.322086180954;6593.736250549972;6614.503912206592;7131.643417829101;2091.215794077221;2123.660214365702;2126.5002724359765;2127.4362293992103;2128.053016407699;2091.7385326834146;2123.7557680866666;2124.320217530391;2124.352072617851;2140.1332460353506;1845.8615603829767;1905.7617797775279;2015.3450128708155;2083.4583008417844;2106.810387065164;2107.9683804742863;2109.430453777479;2111.292716939827;2111.725475688166;2111.7571479279927;2024.5545979884403;2043.4584835018584;2047.7747447780732;2048.8873111978837;2059.577786553753;6981.232064472904;7002.334778196063;7027.754028609025;7047.119110111236;7049.89425158623;4278.807453664593;4366.323219900889;4370.650347972155;4375.037491751809;4441.911161776747;2594.6108083787394;2771.7505424511687;2789.0377819652185;2790.1908446550474;2803.1170181392795;1044.7022598559374;1084.9891501084967;1109.8002359575273;1116.558959211107;1124.449019980212;9312.674056407994;9320.72037838866;9335.719928402159;9405.623775048989;9505.952470237604;3673.9081522961606;3934.7048971326694;3939.290927632996;3980.124372638015;3982.024404021033;7894.763157105296;7898.578255913991;7900.486468379537;7907.48601340911;7909.762707118795;4331.848385306499;4338.327559228324;4364.127174565099;4364.956350436519;4369.934450983238;3635.7454978151277;3735.831887565066;3879.980600097038;4045.1707399982934;4056.8377264909664;3368.444206705915;3419.7859759785415;3493.3013397320638;3501.8599256029474;3554.8400321985573;1120.7198200449889;1125.3360517294836;1131.9943400282946;1133.0482394788417;1136.8635763708407;651.6122906870404;664.9833754156087;665.8535122273092;670.4669787518934;675.5271310082961;4511.120331535319;4622.907541849144;4629.611116664987;4633.30500424939;4758.381410416624;472.8935989402357;477.4437779986277;478.2467613508738;480.47148137048964;481.14596591050463;2969.495185818416;2996.5205567109156;3018.592490013864;3063.172943305295;3111.81329120249;7779.533228006338;7979.16218797018;8111.634976426075;8153.103158652543;8173.918260817434;8176.46852954556;8186.386021048445;8186.959065204635;8192.139825318374;8219.136990616018;6493.318201588765;6663.700133494004;6707.390226345642;6840.452763778886;6843.657817109125;4955.6531042826655;4990.426100998432;4996.625253105959;5016.2224855147515;5066.569341606005;7643.273888980538;7831.373490120891;7836.490628109154;7884;7904.6838126474295;7773.766786996399;7995.12053674107;8031.317338026333;8036.557989310644;8093.473924194908;5837.67892765885;6067.332593414632;6306.621602703853;6428.614283143026;6449.96775016122;6528.836779080561;6729.69716362755;6734;6846.691898864464;6854.280300568368;6193.84515387119;6219.160413344322;6337.746490140343;6358.395952384516;6396.168498095404;6181.289151747606;6187.133801267736;6206.162168107247;6206.565540412128;6226.439620434183;5723.370429252695;5801.100829371454;5816.680082595329;5828.271466066742;5831.591788574929;4022.9798851005557;4058.7564746115327;4074.246264441088;4123.36087906375;4205.705600607928;3993.8202780875513;4010.4585880905706;4017.116234428421;4054.432379466936;4083.040485485849 09:27:17 INFO - PROCESS | 9383 | |4;prototype.html;342.0917464132727;343.21746418166583;346.1553808706757;351.1975136812383;352.1161883671983;4840.733759643218;4886.340344053552;4906.705597664134;4907.362042934418;4911.656184067117;4626.44482662081;4685.929711054337;4744.881377965544;4765.666403351766;4848.490908454614;3904.4338570907203;3912.628300311473;3918.941215881764;3946.763194208342;3960.3861401482673;3906.921861562767;3917.5690674025836;3920.4511368408444;3939.3893946438347;3962.861299854506;3689.7048236141177;3779.017455461586;3803.220339830328;3863.3818589025764;3893.0851249956236;3856.4408160816806;3862.1310205203745;3862.8841134766003;3868.9613103869024;3871.9032024199337;3464.9085538055433;3468.9826550867083;3477.7913325200316;3478.3565040467342;3496.9125771855906;4530.59551539024;4685.273782563674;4732.668713190078;4743.8339658112145;4761.761941895097;4393.824247030115;4409.933850992237;4443.577860103285;4456.754878481682;4499.662525310582;5039;5057.620678449131;5064.696118232918;5070.163423035195;5084;3786.2995345861104;3795.7912314822674;3826.521684789401;3827.5215598050245;3847.442120892455;5045.073857162837;5281.313429254211;5302.016104861456;5311.282976798121;5319.7340132993195;3642.2897534980953;3647.708183345326;3652.470391793175;3653.451982202691;3654.5797233317976;3685.668289853926;3686.2443199144113;3701.2227432239533;3702.1670124222373;3727.8508859645312;4165.66674666026;4235.131797981407;4275.273203555403;4285.228658841438;4299.097189590158;5099.617528685363;5101.20931255656;5108.208227724708;5122.667026643257;5128.743562821844;1191.827185058179;1206.7284860906225;1241.875812418751;1246.2771592476342;1251.7058491254547;819.3486178488116;842.9662813487514;845.4673555659895;855.9400841941005;856.2550580994574;4309.913801723948;4328.675349348811;4334.176506463825;4340.2187606230555;4367.323064924942;5028.371453568304;5065.873353166126;5070.416902056237;5088.92366614501;5120.61743329299;4143;4282.678799090018;4324.545922678137;4331.4152589400655;4370.934435983463;2665.8267212631117;2666.306760242351;2670.5193065248054;2679.196241127654;2682.3964607963444;4723.881902952454;4735.266033764772;4741.336212930123;4749.64377671676;4754.857354279377;4966.503349665077;5152.407473140562;5287.894242115213;5333.946660533345;5342.8557159266375;4851.611871050308;4888.92220806156;4907.460179380194;4911.45973942866;4922.187839006596;5225.399079105951;5230.450802665665;5231.032259032091;5240.4759524047295;5265.947340526545;4895.510448955076;5110.565601923729;5159.277701121921;5161.66449180802;5162.922556161736;4904.111916911943;4907.165781817028;4908.067467181224;4908.901821963612;4912.729799861042;3617.5658920928604;3636.909077273089;3638.599754027108;3643.3259846928404;3652.3790955537092;5107.591392688651;5161.528964245172;5187.844364669066;5203.791848326176;5219.321488206509;4990.226514890198;5013.623978201576;5014.348134742606;5035.798568057383;5053;5038.697678139323;5055.230669265875;5077.720725360141;5078.542931136216;5098.923516147187;4744.7390393528685;4760.571548560646;4867.878303042452;4960.975195124001;4976.810203510444;4937;5055.292259083805;5058.747062646927;5067.9239811403595;5080.796768129233;4910.993246384412;4946.282788995647;4948.501006991446;4985.925211121764;4991.301217829579 09:27:17 INFO - PROCESS | 9383 | |5;yui.html;659.1332397896768;668.3116390118174;732.9670164842581;763.9388848892088;783.2559068884561;12043.458044388002;13347.866521334796;13358.799618005738;13371.665708357272;13383.130096543719;12291.692707682312;12319;12337.25976441414;12353.19704219225;12364.752704945919;661.9007148927651;680.829792551862;767.8157242261859;778.81697801017;792.5838934559345;809.352517985612;809.4050872608657;811.8619834628128;811.8903947967037;812.1350761439048;858.1247127929508;859.1451505751786;859.3297228162044;859.6389516403126;860.0324634785866;705.9541129826596;719.8056524738291;825.5830805443213;857.0272740439615;857.2327766648846;616.996915015422;621.3662064694032;622.3993845938645;622.4398041762405;622.5362105231632;12374.195677280948;12394.81407778884;12414.62756117318;12431.875681243253;12458.688532786662;10964.28732132409;11023.228374013823;11148.832767508495;11359.772804543863;11617.012553932926;11570.272670132023;11609.245399049034;11684.12369072323;11824.054075673925;11977.101717371144;4014.136960553466;4044.8786536403954;4094.529129150126;4114.958850411487;4116.608922152421;61791;61877.381226187616;62631.37368626301;64502;64826.67586662083;9926.702198934043;9947.303688741718;9983.500824958723;10098.192144628412;10227.488625568692;14976.850231497763;15002.849971500364;15417.460388886335;18955.526111847037;19089.713654295196;6377.808665739943;6489.383508566679;6567.2447668518735;6688.398044176048;6746.460283177334;3400.5239266502213;3473.513708080871;3526.682598566141;3558.839852206657;3626.4560315952817;2823.3224026233556;2864.069177517315;2889.277680579855;2891.6530016397746;2909.8254104753782;480.5074798331738;485.9295402166666;491.5625093666568;492.4705941113246;495.71991824618976;585.2070444547662;598.396692498288;599.6312267955176;609.4027852704374;617.0374216222707;8732.083131271253;8818.823623527494;9009.504477253813;9021.684241051531;9113.225375842994;492.99753501232266;513.3403576404303;518.429727299967;521.559282406366;525.9027079990215;3811.27585758705;3904.572604227409;3961.5246170459727;4107.568705285962;4160.355144952538;47833.521664783606;56018.75690511102;59731.40268597345;63004.68497657483;63114.36885631085;51880.74059629753;52863.47136528663;53302;54108.45891541113;56445.71777141088;43001.78499107484;43453.99999999937;43966.780166098964;47994.5200547997;48739.51260487421;8678.175573320525;9027.548622568976;9380.437173769596;9438.95280523593;9461.438862587871;29120.854395727885;34560.82719586386;35252.294954101286;35747.82126089353;36348.27303453969;41248;43801.34297985469;44105.33841992437;44203.778981104886;44304.7784761087;10456.32033917793;10787.190960677824;10790.406527641055;10821.673265450716;11414.600488982845;12318.322492263012;12459.501619935101;12474.313912734886;12726.490940362282;12786.041046921331;10347.482625868826;10471.528781204712;10741.946290268812;10895.237333386587;11241.55033798639;11679.883201168219;11714.765704685688;12210.75578488443;12218.938905305416;12440.68898277514;11066.723331916444;11289.830652540055;11303.547858085913;11844.466998984894;11847.466863991314;9806.842494651166;9885.209183265179;10104.747381315527;10191.286609937233;10313.690589282334;9333.626654933998;9380.718578442658;9383.249340052917;9415.2467802577;9427.528623568656 09:27:17 INFO - PROCESS | 9383 | __end_tp_report 09:27:17 INFO - PROCESS | 9383 | __start_cc_report 09:27:17 INFO - PROCESS | 9383 | _x_x_mozilla_cycle_collect,1151 09:27:17 INFO - PROCESS | 9383 | __end_cc_report 09:27:17 INFO - PROCESS | 9383 | __startTimestamp1483810037802__endTimestamp 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | ------- Summary: start ------- 09:27:17 INFO - PROCESS | 9383 | Number of tests: 6 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | [#0] dojo.html Cycles:165 Average:4782.96 Median:4873.71 stddev:505.61 (10.4%) stddev-sans-first:477.19 09:27:17 INFO - PROCESS | 9383 | Values: 2596.8 2608.5 2623.3 2635.1 2635.3 4731.1 4766.7 4784.2 4823.3 4851.1 4774.8 4825.1 4831.9 4832.4 4870.3 4408.7 4438.3 4451.3 4460.6 4542.3 4530.1 4534.7 4536.7 4575.8 4617.8 4400.6 4440.6 4442.3 4508.2 4546.2 4397.2 4441.2 4458.5 4474.8 4532.9 4423.9 4435.7 4452.3 4454.2 4472.0 4722.2 4790.8 4832.6 4859.9 4860.4 5089.7 5118.4 5165.4 5170.9 5229.3 5187.4 5199.6 5210.9 5210.9 5215.9 4831.1 4838.7 4841.1 4846.9 4881.8 5183.2 5247.7 5251.7 5308.0 5308.4 5099.1 5100.0 5102.5 5114.1 5204.6 5067.7 5110.2 5224.5 5229.5 5239.3 5034.9 5126.5 5162.2 5165.8 5238.1 5163.9 5175.5 5175.8 5179.6 5181.7 4394.4 4428.6 4431.0 4445.2 4465.5 3829.3 3833.8 3878.6 3878.8 3890.4 4453.8 4457.3 4458.1 4463.8 4503.7 5121.3 5176.9 5225.5 5234.3 5289.7 4393.6 4420.3 4422.6 4446.2 4487.4 4677.9 4683.4 4685.8 4687.2 4737.8 4786.8 4827.9 4844.0 4844.8 4882.5 5165.0 5202.3 5220.7 5222.3 5254.6 4851.7 4861.8 4870.8 4873.7 4917.0 4993.8 5038.8 5142.0 5145.3 5151.6 5081.2 5134.4 5149.3 5183.5 5239.1 5019.8 5029.4 5033.1 5034.7 5080.7 4917.9 5013.8 5015.1 5015.4 5039.3 4902.8 4945.4 4948.4 4965.4 5019.8 5080.5 5081.0 5081.9 5086.8 5136.5 4848.7 4945.8 4948.2 4950.2 4995.9 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | [#1] ext.html Cycles:180 Average:46046.58 Median:36083.28 stddev:76859.68 (213.0%) stddev-sans-first:76999.17 09:27:17 INFO - PROCESS | 9383 | Values: 487.3 507.3 512.3 517.0 527.4 28930.3 39334.4 41753.0 41832.0 45380.0 41790.6 42234.8 42273.8 42328.4 42340.4 7605.3 7620.5 7835.5 7837.6 7847.6 7541.2 9556.2 9828.6 9833.1 10139.7 10119.5 10188.9 10192.7 10203.1 10203.2 10035.0 10083.3 10102.1 10102.1 10104.5 9869.7 9926.8 9941.9 9944.2 9946.9 39643.4 40014.0 40032.6 40081.8 40125.8 3522.4 3656.9 3707.0 3712.0 3716.6 28090.6 28535.3 30247.2 31092.2 31834.5 3356.3 3449.7 3464.4 3498.6 3504.0 240018.0 243083.0 244908.0 244979.0 245710.8 59863.4 60725.4 60800.4 60802.4 60964.4 64137.4 64546.4 64555.4 64556.4 64558.7 12317.1 12649.2 12661.4 12663.2 12663.4 8613.0 8893.0 9003.2 9225.3 9393.9 17916.3 17985.8 18017.3 18040.5 18625.6 7366.3 7518.3 7622.2 7633.2 7694.6 794.6 805.4 810.9 812.2 831.2 27423.9 28581.7 28812.7 29132.6 29393.1 949.9 952.5 970.7 983.2 988.9 14488.7 15303.9 15459.5 15669.7 15716.4 340588.0 344093.0 344361.0 344737.0 345106.0 50626.7 51270.5 51441.0 51441.7 51584.7 1214.8 1246.1 1252.7 1262.3 1268.2 25857.9 26694.9 27624.3 27848.7 27995.6 44418.6 44466.3 44467.0 44470.8 44923.8 282524.0 282696.0 284797.0 285220.0 285554.0 33517.0 34339.7 38137.0 38721.0 38924.4 42094.8 42707.4 42718.8 43154.6 43660.3 35641.5 35918.3 36070.3 36170.8 36855.8 35509.3 35605.5 35967.6 36096.3 36181.6 29676.5 29906.4 29911.4 30120.2 30204.2 29647.1 31627.7 31961.2 32284.5 32329.4 22082.6 23457.0 24534.9 24580.4 24780.3 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | [#2] jquery.html Cycles:180 Average:27311.56 Median:494069.50 stddev:79747.88 (16.1%) stddev-sans-first:79945.88 09:27:17 INFO - PROCESS | 9383 | Values: 345.3 353.7 377.6 378.1 391.3 13521.9 13894.2 18501.5 18533.6 18547.3 17713.0 17874.9 18013.6 18239.9 18258.1 3957.4 5003.2 5040.9 5068.0 5140.1 4032.9 4050.2 4146.6 4146.9 5959.0 5940.1 6133.1 6156.6 6174.0 6179.6 6065.5 6100.6 6110.0 6117.0 6134.6 5168.8 5330.4 5337.2 5359.0 5428.7 21865.5 21900.6 22024.9 22093.4 22151.3 2254.9 2282.6 2302.4 2322.8 2378.2 12863.6 13130.4 13166.3 13442.5 13503.7 1724.7 1740.3 1771.3 1776.3 1786.7 72370.0 72475.6 73620.6 74034.6 75005.6 9557.2 9848.9 9907.6 9970.0 10000.6 13260.7 13269.9 13276.9 13280.3 13412.9 6309.5 6495.5 6632.2 6637.5 6680.5 5890.7 5932.9 6080.4 6096.1 6105.3 6302.5 6409.8 6438.8 6455.3 6509.7 1661.5 1729.3 1776.5 1785.8 1791.1 798.7 805.2 819.9 822.0 823.9 17002.0 17271.9 17441.3 18036.6 18109.9 801.8 803.2 807.6 812.8 825.8 9856.3 10413.7 10441.5 10456.1 10555.4 467364.0 478154.0 493252.0 494887.0 495148.0 58882.0 79275.6 81352.6 81542.6 81717.6 909.4 935.2 957.1 958.2 963.4 15463.2 15544.1 15952.4 16156.0 16301.8 35755.5 36786.8 37087.6 37129.4 37194.6 50363.7 50612.5 50661.7 50786.7 50913.0 7373.4 7448.2 7553.8 7573.2 7745.2 11268.4 12049.2 12295.7 12735.4 12977.3 9740.2 9914.0 9941.7 10078.8 10652.1 10619.7 10939.2 10994.1 11084.4 11364.1 9943.7 10243.1 10255.9 10539.0 10653.8 7619.2 7744.1 7759.2 7897.1 7900.0 7430.9 9782.3 9997.0 10195.7 10339.1 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | [#3] mootools.html Cycles:180 Average:4625.06 Median:2617.91 stddev:2507.71 (95.8%) stddev-sans-first:2501.05 09:27:17 INFO - PROCESS | 9383 | Values: 1139.0 1143.2 1147.1 1148.9 1177.0 6884.3 7174.6 7190.9 7194.2 7211.8 6450.4 6456.3 6593.7 6614.5 7131.6 2091.2 2123.7 2126.5 2127.4 2128.1 2091.7 2123.8 2124.3 2124.4 2140.1 1845.9 1905.8 2015.3 2083.5 2106.8 2108.0 2109.4 2111.3 2111.7 2111.8 2024.6 2043.5 2047.8 2048.9 2059.6 6981.2 7002.3 7027.8 7047.1 7049.9 4278.8 4366.3 4370.7 4375.0 4441.9 2594.6 2771.8 2789.0 2790.2 2803.1 1044.7 1085.0 1109.8 1116.6 1124.4 9312.7 9320.7 9335.7 9405.6 9506.0 3673.9 3934.7 3939.3 3980.1 3982.0 7894.8 7898.6 7900.5 7907.5 7909.8 4331.8 4338.3 4364.1 4365.0 4369.9 3635.7 3735.8 3880.0 4045.2 4056.8 3368.4 3419.8 3493.3 3501.9 3554.8 1120.7 1125.3 1132.0 1133.0 1136.9 651.6 665.0 665.9 670.5 675.5 4511.1 4622.9 4629.6 4633.3 4758.4 472.9 477.4 478.2 480.5 481.1 2969.5 2996.5 3018.6 3063.2 3111.8 7779.5 7979.2 8111.6 8153.1 8173.9 8176.5 8186.4 8187.0 8192.1 8219.1 6493.3 6663.7 6707.4 6840.5 6843.7 4955.7 4990.4 4996.6 5016.2 5066.6 7643.3 7831.4 7836.5 7884.0 7904.7 7773.8 7995.1 8031.3 8036.6 8093.5 5837.7 6067.3 6306.6 6428.6 6450.0 6528.8 6729.7 6734.0 6846.7 6854.3 6193.8 6219.2 6337.7 6358.4 6396.2 6181.3 6187.1 6206.2 6206.6 6226.4 5723.4 5801.1 5816.7 5828.3 5831.6 4023.0 4058.8 4074.2 4123.4 4205.7 3993.8 4010.5 4017.1 4054.4 4083.0 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | [#4] prototype.html Cycles:180 Average:4201.78 Median:4747.26 stddev:1207.01 (25.4%) stddev-sans-first:1175.11 09:27:17 INFO - PROCESS | 9383 | Values: 342.1 343.2 346.2 351.2 352.1 4840.7 4886.3 4906.7 4907.4 4911.7 4626.4 4685.9 4744.9 4765.7 4848.5 3904.4 3912.6 3918.9 3946.8 3960.4 3906.9 3917.6 3920.5 3939.4 3962.9 3689.7 3779.0 3803.2 3863.4 3893.1 3856.4 3862.1 3862.9 3869.0 3871.9 3464.9 3469.0 3477.8 3478.4 3496.9 4530.6 4685.3 4732.7 4743.8 4761.8 4393.8 4409.9 4443.6 4456.8 4499.7 5039.0 5057.6 5064.7 5070.2 5084.0 3786.3 3795.8 3826.5 3827.5 3847.4 5045.1 5281.3 5302.0 5311.3 5319.7 3642.3 3647.7 3652.5 3653.5 3654.6 3685.7 3686.2 3701.2 3702.2 3727.9 4165.7 4235.1 4275.3 4285.2 4299.1 5099.6 5101.2 5108.2 5122.7 5128.7 1191.8 1206.7 1241.9 1246.3 1251.7 819.3 843.0 845.5 855.9 856.3 4309.9 4328.7 4334.2 4340.2 4367.3 5028.4 5065.9 5070.4 5088.9 5120.6 4143.0 4282.7 4324.5 4331.4 4370.9 2665.8 2666.3 2670.5 2679.2 2682.4 4723.9 4735.3 4741.3 4749.6 4754.9 4966.5 5152.4 5287.9 5333.9 5342.9 4851.6 4888.9 4907.5 4911.5 4922.2 5225.4 5230.5 5231.0 5240.5 5265.9 4895.5 5110.6 5159.3 5161.7 5162.9 4904.1 4907.2 4908.1 4908.9 4912.7 3617.6 3636.9 3638.6 3643.3 3652.4 5107.6 5161.5 5187.8 5203.8 5219.3 4990.2 5013.6 5014.3 5035.8 5053.0 5038.7 5055.2 5077.7 5078.5 5098.9 4744.7 4760.6 4867.9 4961.0 4976.8 4937.0 5055.3 5058.7 5067.9 5080.8 4911.0 4946.3 4948.5 4985.9 4991.3 09:27:17 INFO - PROCESS | 9383 | 09:27:17 INFO - PROCESS | 9383 | [#5] yui.html Cycles:180 Average:14108.93 Median:44154.56 stddev:17116.61 (38.8%) stddev-sans-first:17134.83 09:27:17 INFO - PROCESS | 9383 | Values: 659.1 668.3 733.0 763.9 783.3 12043.5 13347.9 13358.8 13371.7 13383.1 12291.7 12319.0 12337.3 12353.2 12364.8 661.9 680.8 767.8 778.8 792.6 809.4 809.4 811.9 811.9 812.1 858.1 859.1 859.3 859.6 860.0 706.0 719.8 825.6 857.0 857.2 617.0 621.4 622.4 622.4 622.5 12374.2 12394.8 12414.6 12431.9 12458.7 10964.3 11023.2 11148.8 11359.8 11617.0 11570.3 11609.2 11684.1 11824.1 11977.1 4014.1 4044.9 4094.5 4115.0 4116.6 61791.0 61877.4 62631.4 64502.0 64826.7 9926.7 9947.3 9983.5 10098.2 10227.5 14976.9 15002.8 15417.5 18955.5 19089.7 6377.8 6489.4 6567.2 6688.4 6746.5 3400.5 3473.5 3526.7 3558.8 3626.5 2823.3 2864.1 2889.3 2891.7 2909.8 480.5 485.9 491.6 492.5 495.7 585.2 598.4 599.6 609.4 617.0 8732.1 8818.8 9009.5 9021.7 9113.2 493.0 513.3 518.4 521.6 525.9 3811.3 3904.6 3961.5 4107.6 4160.4 47833.5 56018.8 59731.4 63004.7 63114.4 51880.7 52863.5 53302.0 54108.5 56445.7 43001.8 43454.0 43966.8 47994.5 48739.5 8678.2 9027.5 9380.4 9439.0 9461.4 29120.9 34560.8 35252.3 35747.8 36348.3 41248.0 43801.3 44105.3 44203.8 44304.8 10456.3 10787.2 10790.4 10821.7 11414.6 12318.3 12459.5 12474.3 12726.5 12786.0 10347.5 10471.5 10741.9 10895.2 11241.6 11679.9 11714.8 12210.8 12218.9 12440.7 11066.7 11289.8 11303.5 11844.5 11847.5 9806.8 9885.2 10104.7 10191.3 10313.7 9333.6 9380.7 9383.2 9415.2 9427.5 09:27:17 INFO - PROCESS | 9383 | -------- Summary: end -------- 09:27:17 INFO - PROCESS | 9383 | 09:27:18 INFO - TEST-INFO | 9383: exit 0 09:27:18 INFO - TEST-OK | dromaeo_css | took 1121522ms 09:27:18 INFO - TEST-START | kraken 09:27:18 INFO - operating with platform_type : linux_ 09:27:18 INFO - Initialising browser for kraken test... 09:27:18 INFO - TEST-INFO | started process 9684 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkeUIeg/profile http://localhost:38805/getInfo.html) 09:27:19 INFO - PROCESS | 9684 | 1483810039188 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 09:27:22 INFO - PROCESS | 9684 | __metrics Screen width/height:1600/1200 09:27:22 INFO - PROCESS | 9684 | colorDepth:24 09:27:22 INFO - PROCESS | 9684 | Browser inner width/height: 1024/697 09:27:22 INFO - PROCESS | 9684 | __metrics 09:27:23 INFO - PROCESS | 9684 | 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" 09:27:23 INFO - PROCESS | 9684 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:27:23 INFO - PROCESS | 9684 | console.error: PushService: 09:27:23 INFO - PROCESS | 9684 | stateChangeProcessEnqueue: Error transitioning state 09:27:23 INFO - PROCESS | 9684 | UnknownError 09:27:24 INFO - TEST-INFO | 9684: exit 0 09:27:24 INFO - Browser initialized. 09:27:24 INFO - Running cycle 1/1 for kraken test... 09:27:24 INFO - TEST-INFO | started process 9765 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkeUIeg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:36 INFO - PROCESS | 9765 | RSS: Main: 152092672 09:27:36 INFO - PROCESS | 9765 | 09:27:39 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38805/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:40 INFO - PROCESS | 9765 | RSS: Main: 205824000 09:27:40 INFO - PROCESS | 9765 | 09:27:42 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38805/tests/kraken/driver.html?testName=audio-dft) 09:27:43 INFO - PROCESS | 9765 | RSS: Main: 186200064 09:27:43 INFO - PROCESS | 9765 | 09:27:46 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38805/tests/kraken/driver.html?testName=audio-fft) 09:27:47 INFO - PROCESS | 9765 | RSS: Main: 269168640 09:27:47 INFO - PROCESS | 9765 | 09:27:49 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38805/tests/kraken/driver.html?testName=audio-oscillator) 09:27:49 INFO - PROCESS | 9765 | RSS: Main: 179757056 09:27:49 INFO - PROCESS | 9765 | 09:27:52 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38805/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:52 INFO - PROCESS | 9765 | RSS: Main: 183648256 09:27:52 INFO - PROCESS | 9765 | 09:27:55 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38805/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:56 INFO - PROCESS | 9765 | RSS: Main: 222298112 09:27:56 INFO - PROCESS | 9765 | 09:27:59 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38805/tests/kraken/driver.html?testName=imaging-desaturate) 09:28:00 INFO - PROCESS | 9765 | RSS: Main: 222289920 09:28:00 INFO - PROCESS | 9765 | 09:28:03 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38805/tests/kraken/driver.html?testName=json-parse-financial) 09:28:03 INFO - PROCESS | 9765 | RSS: Main: 219893760 09:28:03 INFO - PROCESS | 9765 | 09:28:05 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38805/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:28:06 INFO - PROCESS | 9765 | RSS: Main: 182898688 09:28:06 INFO - PROCESS | 9765 | 09:28:08 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:28:08 INFO - PROCESS | 9765 | RSS: Main: 190242816 09:28:08 INFO - PROCESS | 9765 | 09:28:10 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:28:11 INFO - PROCESS | 9765 | RSS: Main: 198025216 09:28:11 INFO - PROCESS | 9765 | 09:28:14 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:28:15 INFO - PROCESS | 9765 | RSS: Main: 202838016 09:28:15 INFO - PROCESS | 9765 | 09:28:18 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:28:18 INFO - PROCESS | 9765 | RSS: Main: 204455936 09:28:18 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | Cycle 1(1): loaded http://localhost:38805/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38805/tests/kraken/driver.html?testName=ai-astar) 09:28:20 INFO - PROCESS | 9765 | RSS: Main: 254709760 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | __start_tp_report 09:28:20 INFO - PROCESS | 9765 | _x_x_mozilla_page_load 09:28:20 INFO - PROCESS | 9765 | _x_x_mozilla_page_load_details 09:28:20 INFO - PROCESS | 9765 | |i|pagename|runs| 09:28:20 INFO - PROCESS | 9765 | |0;ai-astar;112;114;113;114;114;113;113;113;114;114 09:28:20 INFO - PROCESS | 9765 | |1;audio-beat-detection;150;126;125;180;128;125;122;125;150;129 09:28:20 INFO - PROCESS | 9765 | |2;audio-dft;162;163;162;165;162;163;162;165;165;165 09:28:20 INFO - PROCESS | 9765 | |3;audio-fft;76;75;79;105;77;78;77;76;105;77 09:28:20 INFO - PROCESS | 9765 | |4;audio-oscillator;85;84;115;85;86;85;96;86;85;111 09:28:20 INFO - PROCESS | 9765 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 09:28:20 INFO - PROCESS | 9765 | |6;imaging-darkroom;149;149;149;149;149;148;150;150;148;149 09:28:20 INFO - PROCESS | 9765 | |7;imaging-desaturate;95;95;96;96;95;96;95;95;98;95 09:28:20 INFO - PROCESS | 9765 | |8;json-parse-financial;59;59;58;58;59;60;60;61;60;60 09:28:20 INFO - PROCESS | 9765 | |9;json-stringify-tinderbox;68;70;68;68;67;69;68;67;68;68 09:28:20 INFO - PROCESS | 9765 | |10;stanford-crypto-aes;85;95;86;85;85;84;84;86;85;84 09:28:20 INFO - PROCESS | 9765 | |11;stanford-crypto-ccm;133;132;145;132;136;134;133;134;221;141 09:28:20 INFO - PROCESS | 9765 | |12;stanford-crypto-pbkdf2;185;182;175;177;178;177;175;176;177;177 09:28:20 INFO - PROCESS | 9765 | |13;stanford-crypto-sha256-iterative;69;68;68;71;70;67;68;69;68;68 09:28:20 INFO - PROCESS | 9765 | __end_tp_report 09:28:20 INFO - PROCESS | 9765 | __start_cc_report 09:28:20 INFO - PROCESS | 9765 | _x_x_mozilla_cycle_collect,1812 09:28:20 INFO - PROCESS | 9765 | __end_cc_report 09:28:20 INFO - PROCESS | 9765 | __startTimestamp1483810100743__endTimestamp 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | ------- Summary: start ------- 09:28:20 INFO - PROCESS | 9765 | Number of tests: 14 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.53 09:28:20 INFO - PROCESS | 9765 | Values: 112.0 114.0 113.0 114.0 114.0 113.0 113.0 113.0 114.0 114.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:128.50 stddev:18.56 (14.4%) stddev-sans-first:18.98 09:28:20 INFO - PROCESS | 9765 | Values: 150.0 126.0 125.0 180.0 128.0 125.0 122.0 125.0 150.0 129.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#2] audio-dft Cycles:10 Average:163.40 Median:164.00 stddev:1.43 (0.9%) stddev-sans-first:1.42 09:28:20 INFO - PROCESS | 9765 | Values: 162.0 163.0 162.0 165.0 162.0 163.0 162.0 165.0 165.0 165.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#3] audio-fft Cycles:10 Average:82.50 Median:77.00 stddev:11.91 (15.5%) stddev-sans-first:12.40 09:28:20 INFO - PROCESS | 9765 | Values: 76.0 75.0 79.0 105.0 77.0 78.0 77.0 76.0 105.0 77.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:85.00 stddev:11.73 (13.8%) stddev-sans-first:12.18 09:28:20 INFO - PROCESS | 9765 | Values: 85.0 84.0 115.0 85.0 86.0 85.0 96.0 86.0 85.0 111.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:28:20 INFO - PROCESS | 9765 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 09:28:20 INFO - PROCESS | 9765 | Values: 149.0 149.0 149.0 149.0 149.0 148.0 150.0 150.0 148.0 149.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 09:28:20 INFO - PROCESS | 9765 | Values: 95.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 98.0 95.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:60.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 09:28:20 INFO - PROCESS | 9765 | Values: 59.0 59.0 58.0 58.0 59.0 60.0 60.0 61.0 60.0 60.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 09:28:20 INFO - PROCESS | 9765 | Values: 68.0 70.0 68.0 68.0 67.0 69.0 68.0 67.0 68.0 68.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:85.00 stddev:3.28 (3.9%) stddev-sans-first:3.46 09:28:20 INFO - PROCESS | 9765 | Values: 85.0 95.0 86.0 85.0 85.0 84.0 84.0 86.0 85.0 84.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:135.00 stddev:27.35 (20.3%) stddev-sans-first:28.71 09:28:20 INFO - PROCESS | 9765 | Values: 133.0 132.0 145.0 132.0 136.0 134.0 133.0 134.0 221.0 141.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:177.00 stddev:3.18 (1.8%) stddev-sans-first:2.09 09:28:20 INFO - PROCESS | 9765 | Values: 185.0 182.0 175.0 177.0 178.0 177.0 175.0 176.0 177.0 177.0 09:28:20 INFO - PROCESS | 9765 | 09:28:20 INFO - PROCESS | 9765 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.50 stddev:1.17 (1.7%) stddev-sans-first:1.24 09:28:20 INFO - PROCESS | 9765 | Values: 69.0 68.0 68.0 71.0 70.0 67.0 68.0 69.0 68.0 68.0 09:28:20 INFO - PROCESS | 9765 | -------- Summary: end -------- 09:28:20 INFO - PROCESS | 9765 | 09:28:21 INFO - TEST-INFO | 9765: exit 0 09:28:21 INFO - TEST-OK | kraken | took 63242ms 09:28:21 INFO - SUITE-END | took 1184s 09:28:22 INFO - Completed test suite (00:19:45) 09:28:22 INFO - javascript benchmark 09:28:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2596.831205971612, 2608.5435048866443, 2623.2523730736743, 2635.1304069657017, 2635.341164708823, 4731.124741922738, 4766.73782941938, 4784.234522476405, 4823.276508523723, 4851.102546028978, 4774.7851346689395, 4825.083234185511, 4831.8792030199265, 4832.395950506187, 4870.269459581055, 4408.669349798762, 4438.3342498625225, 4451.332300154986, 4460.598546130847, 4542.341360502725, 4530.07133537624, 4534.705244159136, 4536.659750518707, 4575.794089265991, 4617.838375656853, 4400.581944715279, 4440.600345968878, 4442.2892337225885, 4508.1659892919915, 4546.249868771649, 4397.208502469554, 4441.200583894898, 4458.509563947963, 4474.7762611869275, 4532.864014079583, 4423.933640995388, 4435.733855968652, 4452.287633978548, 4454.175977444151, 4471.9776401118115, 4722.220833562458, 4790.8323208687525, 4832.589229915427, 4859.902801943941, 4860.392450943632, 5089.720065396402, 5118.411382691001, 5165.431802501722, 5170.948290517122, 5229.346331708536, 5187.403448603422, 5199.6360254782185, 5210.895782084337, 5210.921836172461, 5215.947840521584, 4831.057943700945, 4838.709677419296, 4841.1285968526, 4846.854594362139, 4881.780319885614, 5183.248428977777, 5247.685138891678, 5251.711155886386, 5308.0445519806035, 5308.442613525602, 5099.133147364957, 5100.0, 5102.4642412546145, 5114.053900028507, 5204.609654275869, 5067.721275329893, 5110.182370820651, 5224.477552244821, 5229.450907654642, 5239.345081864767, 5034.874128146753, 5126.53861152498, 5162.199859021857, 5165.767540460688, 5238.135707608279, 5163.922541161885, 5175.534201921845, 5175.767090480862, 5179.637425380187, 5181.715005674724, 4394.36281739146, 4428.601425871687, 4431.047324825178, 4445.244308467568, 4465.531119232499, 3829.349010668193, 3833.750806197588, 3878.5927477614446, 3878.825452854628, 3890.3775395936514, 4453.777311134495, 4457.286834106528, 4458.108378324283, 4463.776811159429, 4503.729776213438, 5121.308623335876, 5176.948230517647, 5225.477452254744, 5234.338376836454, 5289.70906600133, 4393.604575588212, 4420.336949457607, 4422.579854913778, 4446.155230506193, 4487.371767952489, 4677.859664210079, 4683.414573178353, 4685.789139488663, 4687.156311863897, 4737.8104875805275, 4786.784594693317, 4827.927581086356, 4844.0, 4844.806207751721, 4882.4629290777275, 5165.0, 5202.297689811827, 5220.6867587943325, 5222.29499017643, 5254.579633629377, 4851.660383773102, 4861.829835955725, 4870.78081486341, 4873.707577545359, 4917.0, 4993.750312484434, 5038.773255203451, 5142.0, 5145.305383773143, 5151.587872970228, 5081.212412076134, 5134.358205224347, 5149.253358262956, 5183.455737147545, 5239.109351410193, 5019.84940451787, 5029.371328583927, 5033.094043072136, 5034.723090230073, 5080.745962701776, 4917.877053073702, 5013.774380152829, 5015.147424937696, 5015.448300686994, 5039.3448851649055, 4902.828401005948, 4945.406551213878, 4948.356713627206, 4965.354503914468, 5019.7741101651245, 5080.491950805038, 5081.034603425337, 5081.8729531762, 5086.821961231339, 5136.486351364833, 4848.660593758403, 4945.802167913387, 4948.2082866742585, 4950.183219768698, 4995.875103122451], "value": 4749.603298224258, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.26423101117314, 507.266999186176, 512.2776884592722, 516.9870753231169, 527.3645257464756, 28930.276743081406, 39334.409983850266, 41753.0, 41832.0, 45380.0, 41790.58209417905, 42234.788826055825, 42273.78863105688, 42328.36507452383, 42340.36489452653, 7605.315521603055, 7620.542767433972, 7835.451518393715, 7837.647305871249, 7847.6468558914985, 7541.170471248186, 9556.18772404343, 9828.606855725762, 9833.065858743408, 10139.695809125738, 10119.494025298742, 10188.949055254714, 10192.74518137045, 10203.08172264496, 10203.234757393226, 10035.0, 10083.344582602107, 10102.141317987905, 10102.14131798798, 10104.545295461685, 9869.654562090293, 9926.80146397068, 9941.850872236922, 9944.204463642891, 9946.90053099467, 39643.40534891979, 40014.0, 40032.59967400318, 40081.79959100186, 40125.79937100325, 3522.4011917974008, 3656.8537258509627, 3706.9814650926573, 3712.0, 3716.6097559756377, 28090.578641320397, 28535.286617834514, 30247.243818904484, 31092.222694432596, 31834.522482162785, 3356.2616224430585, 3449.706360114957, 3464.4456886897974, 3498.5626796650417, 3504.001359612498, 240018.0, 243083.0, 244908.0, 244979.0, 245710.77144614342, 59863.40136598578, 60725.39274607197, 60800.391996079474, 60802.39197607967, 60964.39035609676, 64137.35862641407, 64546.35453645497, 64555.35444645587, 64556.35443645597, 64558.67720661461, 12317.137800354069, 12649.177803442746, 12661.366931653565, 12663.240205587694, 12663.36683165838, 8612.956935215283, 8893.0, 9003.234725048313, 9225.261979041661, 9393.859092113624, 17916.28334866617, 17985.820141798413, 18017.297405389298, 18040.548986275448, 18625.627487450445, 7366.263373662698, 7518.323350898446, 7622.237776222333, 7633.160352361236, 7694.576798276036, 794.6225542867207, 805.3557154276673, 810.9310708589836, 812.2080971053176, 831.1563762780783, 27423.86288068547, 28581.714182857904, 28812.711872881002, 29132.563011554845, 29393.11820645384, 949.9145076943108, 952.4713783849892, 970.6997998865096, 983.2379905573094, 988.8961659025842, 14488.710225795636, 15303.923480382527, 15459.536213913601, 15669.686606267811, 15716.449924252596, 340588.0, 344093.0, 344361.0, 344737.0, 345106.0, 50626.746866265436, 51270.487295127314, 51441.0, 51441.742791285804, 51584.74207628938, 1214.769193853165, 1246.1152581666936, 1252.6680429685957, 1262.280500114944, 1268.1566758105757, 25857.870710646326, 26694.866525667247, 27624.30939226455, 27848.72151278542, 27995.580066298615, 44418.55581444144, 44466.3330050043, 44467.0, 44470.777646111565, 44923.77538112288, 282524.0, 282696.0, 284797.0, 285220.0, 285554.0, 33517.0, 34339.65660343465, 38137.0, 38721.0, 38924.41613375745, 42094.78952605218, 42707.35938960856, 42718.815454677395, 43154.56845431631, 43660.345094822966, 35641.46537801987, 35918.28163436769, 36070.27859442849, 36170.819145904105, 36855.8157209223, 35509.28981420409, 35605.465918010734, 35967.64032359643, 36096.27807443784, 36181.638183617826, 29676.509845678618, 29906.401871962873, 29911.401771964873, 30120.24699382533, 30204.24489387783, 29647.11058668157, 31627.683723162474, 31961.200969975936, 32284.515732263564, 32329.353412931137, 22082.558348833256, 23457.0, 24534.87732561326, 24580.38549036217, 24780.25659230226], "value": 18051.795095349047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.31110434682796, 353.65695275582675, 377.5657993307696, 378.0699479280969, 391.3093390166357, 13521.939016647013, 13894.23581703006, 18501.53746156347, 18533.629327413477, 18547.258109675597, 17713.0, 17874.910625446853, 18013.63972720545, 18239.908800455978, 18258.087095645198, 3957.366821308591, 5003.17447621142, 5040.949590504085, 5067.974660126694, 5140.126178549637, 4032.9395059074136, 4050.16971520839, 4146.60607242313, 4146.917061658765, 5958.97020514899, 5940.07928771039, 6133.11069894865, 6156.630602163862, 6174.043023331368, 6179.5674302798825, 6065.514758819284, 6100.60346077508, 6110.0, 6116.969415152918, 6134.601250918676, 5168.844934651929, 5330.413654498002, 5337.172738225581, 5359.0, 5428.728563571805, 21865.453363666034, 21900.561988760135, 22024.889875550518, 22093.447663808372, 22151.33545993623, 2254.8985295661732, 2282.6233671444356, 2302.3668491164894, 2322.790948814598, 2378.2389635316704, 12863.614091577268, 13130.409131589102, 13166.341682915912, 13442.529511467052, 13503.662408439868, 1724.7499112628725, 1740.321274702867, 1771.2737777511288, 1776.2717285912713, 1786.7319902014672, 72370.0, 72475.63762181209, 73620.63189684018, 74034.62982685106, 75005.62497187534, 9557.23542116629, 9848.931724337826, 9907.554160062815, 9970.002999700117, 10000.599976001024, 13260.73478530424, 13269.867301327056, 13276.87569988797, 13280.269585172908, 13412.93293533526, 6309.463695585927, 6495.512836537278, 6632.237292711304, 6637.535372523877, 6680.498962577826, 5890.705464726747, 5932.881342373128, 6080.391960803884, 6096.055111457731, 6105.297890742621, 6302.464290535356, 6409.775657851953, 6438.774642887476, 6455.289918109004, 6509.707063182168, 1661.5264649574813, 1729.3428497170994, 1776.4936992956946, 1785.7946336171506, 1791.0984205587033, 798.660569258063, 805.1867613710195, 819.8726750717763, 822.0176888618164, 823.9134890836496, 17002.0, 17271.91364043197, 17441.30234790594, 18036.63926721458, 18109.909450452662, 801.8075661841233, 803.2449497472358, 807.5800583696563, 812.8293058457672, 825.7976795685096, 9856.31005829585, 10413.739656508647, 10441.530131144116, 10456.11527926641, 10555.366677999345, 467364.0, 478154.0, 493252.0, 494887.0, 495148.0, 58882.0, 79275.60362198151, 81352.59323703343, 81542.59228703937, 81717.59141204375, 909.372532952274, 935.1957316707626, 957.1481381570408, 958.1999030809213, 963.4123184857232, 15463.226838658247, 15544.05699287589, 15952.361905524112, 16156.0, 16301.836981630031, 35755.46366804448, 36786.8160659195, 37087.6291237095, 37129.443058354685, 37194.62805371912, 50363.74818125886, 50612.493875060776, 50661.74669126631, 50786.746066269436, 50913.0, 7373.410127189919, 7448.180700123091, 7553.811154720956, 7573.166951635426, 7745.22547745221, 11268.436578171222, 12049.216800907912, 12295.69260768488, 12735.363231838557, 12977.286249256382, 9740.220782337254, 9914.0, 9941.701748947544, 10078.848817267894, 10652.147828173882, 10619.681409557727, 10939.179561532757, 10994.120470362512, 11084.390358530358, 11364.147688923198, 9943.701688949342, 10243.07812296897, 10255.948720256649, 10539.0, 10653.786924261316, 7619.161892191743, 7744.148143704076, 7759.185285544936, 7897.091834439113, 7899.960500197462, 7430.92569074324, 9782.266330025419, 9996.950015249877, 10195.694129176136, 10339.0694837465], "value": 8453.659411563996, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1138.982915256271, 1143.182624423537, 1147.0880649883343, 1148.8908553687397, 1177.0289511153298, 6884.3115688431135, 7174.641267936595, 7190.856182876352, 7194.24460431655, 7211.783646490607, 6450.387213214749, 6456.322086180954, 6593.736250549972, 6614.503912206592, 7131.643417829101, 2091.215794077221, 2123.660214365702, 2126.5002724359765, 2127.4362293992103, 2128.053016407699, 2091.7385326834146, 2123.7557680866666, 2124.320217530391, 2124.352072617851, 2140.1332460353506, 1845.8615603829767, 1905.7617797775279, 2015.3450128708155, 2083.4583008417844, 2106.810387065164, 2107.9683804742863, 2109.430453777479, 2111.292716939827, 2111.725475688166, 2111.7571479279927, 2024.5545979884403, 2043.4584835018584, 2047.7747447780732, 2048.8873111978837, 2059.577786553753, 6981.232064472904, 7002.334778196063, 7027.754028609025, 7047.119110111236, 7049.89425158623, 4278.807453664593, 4366.323219900889, 4370.650347972155, 4375.037491751809, 4441.911161776747, 2594.6108083787394, 2771.7505424511687, 2789.0377819652185, 2790.1908446550474, 2803.1170181392795, 1044.7022598559374, 1084.9891501084967, 1109.8002359575273, 1116.558959211107, 1124.449019980212, 9312.674056407994, 9320.72037838866, 9335.719928402159, 9405.623775048989, 9505.952470237604, 3673.9081522961606, 3934.7048971326694, 3939.290927632996, 3980.124372638015, 3982.024404021033, 7894.763157105296, 7898.578255913991, 7900.486468379537, 7907.48601340911, 7909.762707118795, 4331.848385306499, 4338.327559228324, 4364.127174565099, 4364.956350436519, 4369.934450983238, 3635.7454978151277, 3735.831887565066, 3879.980600097038, 4045.1707399982934, 4056.8377264909664, 3368.444206705915, 3419.7859759785415, 3493.3013397320638, 3501.8599256029474, 3554.8400321985573, 1120.7198200449889, 1125.3360517294836, 1131.9943400282946, 1133.0482394788417, 1136.8635763708407, 651.6122906870404, 664.9833754156087, 665.8535122273092, 670.4669787518934, 675.5271310082961, 4511.120331535319, 4622.907541849144, 4629.611116664987, 4633.30500424939, 4758.381410416624, 472.8935989402357, 477.4437779986277, 478.2467613508738, 480.47148137048964, 481.14596591050463, 2969.495185818416, 2996.5205567109156, 3018.592490013864, 3063.172943305295, 3111.81329120249, 7779.533228006338, 7979.16218797018, 8111.634976426075, 8153.103158652543, 8173.918260817434, 8176.46852954556, 8186.386021048445, 8186.959065204635, 8192.139825318374, 8219.136990616018, 6493.318201588765, 6663.700133494004, 6707.390226345642, 6840.452763778886, 6843.657817109125, 4955.6531042826655, 4990.426100998432, 4996.625253105959, 5016.2224855147515, 5066.569341606005, 7643.273888980538, 7831.373490120891, 7836.490628109154, 7884.0, 7904.6838126474295, 7773.766786996399, 7995.12053674107, 8031.317338026333, 8036.557989310644, 8093.473924194908, 5837.67892765885, 6067.332593414632, 6306.621602703853, 6428.614283143026, 6449.96775016122, 6528.836779080561, 6729.69716362755, 6734.0, 6846.691898864464, 6854.280300568368, 6193.84515387119, 6219.160413344322, 6337.746490140343, 6358.395952384516, 6396.168498095404, 6181.289151747606, 6187.133801267736, 6206.162168107247, 6206.565540412128, 6226.439620434183, 5723.370429252695, 5801.100829371454, 5816.680082595329, 5828.271466066742, 5831.591788574929, 4022.9798851005557, 4058.7564746115327, 4074.246264441088, 4123.36087906375, 4205.705600607928, 3993.8202780875513, 4010.4585880905706, 4017.116234428421, 4054.432379466936, 4083.040485485849], "value": 3724.403211427777, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.0917464132727, 343.21746418166583, 346.1553808706757, 351.1975136812383, 352.1161883671983, 4840.733759643218, 4886.340344053552, 4906.705597664134, 4907.362042934418, 4911.656184067117, 4626.44482662081, 4685.929711054337, 4744.881377965544, 4765.666403351766, 4848.490908454614, 3904.4338570907203, 3912.628300311473, 3918.941215881764, 3946.763194208342, 3960.3861401482673, 3906.921861562767, 3917.5690674025836, 3920.4511368408444, 3939.3893946438347, 3962.861299854506, 3689.7048236141177, 3779.017455461586, 3803.220339830328, 3863.3818589025764, 3893.0851249956236, 3856.4408160816806, 3862.1310205203745, 3862.8841134766003, 3868.9613103869024, 3871.9032024199337, 3464.9085538055433, 3468.9826550867083, 3477.7913325200316, 3478.3565040467342, 3496.9125771855906, 4530.59551539024, 4685.273782563674, 4732.668713190078, 4743.8339658112145, 4761.761941895097, 4393.824247030115, 4409.933850992237, 4443.577860103285, 4456.754878481682, 4499.662525310582, 5039.0, 5057.620678449131, 5064.696118232918, 5070.163423035195, 5084.0, 3786.2995345861104, 3795.7912314822674, 3826.521684789401, 3827.5215598050245, 3847.442120892455, 5045.073857162837, 5281.313429254211, 5302.016104861456, 5311.282976798121, 5319.7340132993195, 3642.2897534980953, 3647.708183345326, 3652.470391793175, 3653.451982202691, 3654.5797233317976, 3685.668289853926, 3686.2443199144113, 3701.2227432239533, 3702.1670124222373, 3727.8508859645312, 4165.66674666026, 4235.131797981407, 4275.273203555403, 4285.228658841438, 4299.097189590158, 5099.617528685363, 5101.20931255656, 5108.208227724708, 5122.667026643257, 5128.743562821844, 1191.827185058179, 1206.7284860906225, 1241.875812418751, 1246.2771592476342, 1251.7058491254547, 819.3486178488116, 842.9662813487514, 845.4673555659895, 855.9400841941005, 856.2550580994574, 4309.913801723948, 4328.675349348811, 4334.176506463825, 4340.2187606230555, 4367.323064924942, 5028.371453568304, 5065.873353166126, 5070.416902056237, 5088.92366614501, 5120.61743329299, 4143.0, 4282.678799090018, 4324.545922678137, 4331.4152589400655, 4370.934435983463, 2665.8267212631117, 2666.306760242351, 2670.5193065248054, 2679.196241127654, 2682.3964607963444, 4723.881902952454, 4735.266033764772, 4741.336212930123, 4749.64377671676, 4754.857354279377, 4966.503349665077, 5152.407473140562, 5287.894242115213, 5333.946660533345, 5342.8557159266375, 4851.611871050308, 4888.92220806156, 4907.460179380194, 4911.45973942866, 4922.187839006596, 5225.399079105951, 5230.450802665665, 5231.032259032091, 5240.4759524047295, 5265.947340526545, 4895.510448955076, 5110.565601923729, 5159.277701121921, 5161.66449180802, 5162.922556161736, 4904.111916911943, 4907.165781817028, 4908.067467181224, 4908.901821963612, 4912.729799861042, 3617.5658920928604, 3636.909077273089, 3638.599754027108, 3643.3259846928404, 3652.3790955537092, 5107.591392688651, 5161.528964245172, 5187.844364669066, 5203.791848326176, 5219.321488206509, 4990.226514890198, 5013.623978201576, 5014.348134742606, 5035.798568057383, 5053.0, 5038.697678139323, 5055.230669265875, 5077.720725360141, 5078.542931136216, 5098.923516147187, 4744.7390393528685, 4760.571548560646, 4867.878303042452, 4960.975195124001, 4976.810203510444, 4937.0, 5055.292259083805, 5058.747062646927, 5067.9239811403595, 5080.796768129233, 4910.993246384412, 4946.282788995647, 4948.501006991446, 4985.925211121764, 4991.301217829579], "value": 3826.197534899829, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.1332397896768, 668.3116390118174, 732.9670164842581, 763.9388848892088, 783.2559068884561, 12043.458044388002, 13347.866521334796, 13358.799618005738, 13371.665708357272, 13383.130096543719, 12291.692707682312, 12319.0, 12337.25976441414, 12353.19704219225, 12364.752704945919, 661.9007148927651, 680.829792551862, 767.8157242261859, 778.81697801017, 792.5838934559345, 809.352517985612, 809.4050872608657, 811.8619834628128, 811.8903947967037, 812.1350761439048, 858.1247127929508, 859.1451505751786, 859.3297228162044, 859.6389516403126, 860.0324634785866, 705.9541129826596, 719.8056524738291, 825.5830805443213, 857.0272740439615, 857.2327766648846, 616.996915015422, 621.3662064694032, 622.3993845938645, 622.4398041762405, 622.5362105231632, 12374.195677280948, 12394.81407778884, 12414.62756117318, 12431.875681243253, 12458.688532786662, 10964.28732132409, 11023.228374013823, 11148.832767508495, 11359.772804543863, 11617.012553932926, 11570.272670132023, 11609.245399049034, 11684.12369072323, 11824.054075673925, 11977.101717371144, 4014.136960553466, 4044.8786536403954, 4094.529129150126, 4114.958850411487, 4116.608922152421, 61791.0, 61877.381226187616, 62631.37368626301, 64502.0, 64826.67586662083, 9926.702198934043, 9947.303688741718, 9983.500824958723, 10098.192144628412, 10227.488625568692, 14976.850231497763, 15002.849971500364, 15417.460388886335, 18955.526111847037, 19089.713654295196, 6377.808665739943, 6489.383508566679, 6567.2447668518735, 6688.398044176048, 6746.460283177334, 3400.5239266502213, 3473.513708080871, 3526.682598566141, 3558.839852206657, 3626.4560315952817, 2823.3224026233556, 2864.069177517315, 2889.277680579855, 2891.6530016397746, 2909.8254104753782, 480.5074798331738, 485.9295402166666, 491.5625093666568, 492.4705941113246, 495.71991824618976, 585.2070444547662, 598.396692498288, 599.6312267955176, 609.4027852704374, 617.0374216222707, 8732.083131271253, 8818.823623527494, 9009.504477253813, 9021.684241051531, 9113.225375842994, 492.99753501232266, 513.3403576404303, 518.429727299967, 521.559282406366, 525.9027079990215, 3811.27585758705, 3904.572604227409, 3961.5246170459727, 4107.568705285962, 4160.355144952538, 47833.521664783606, 56018.75690511102, 59731.40268597345, 63004.68497657483, 63114.36885631085, 51880.74059629753, 52863.47136528663, 53302.0, 54108.45891541113, 56445.71777141088, 43001.78499107484, 43453.99999999937, 43966.780166098964, 47994.5200547997, 48739.51260487421, 8678.175573320525, 9027.548622568976, 9380.437173769596, 9438.95280523593, 9461.438862587871, 29120.854395727885, 34560.82719586386, 35252.294954101286, 35747.82126089353, 36348.27303453969, 41248.0, 43801.34297985469, 44105.33841992437, 44203.778981104886, 44304.7784761087, 10456.32033917793, 10787.190960677824, 10790.406527641055, 10821.673265450716, 11414.600488982845, 12318.322492263012, 12459.501619935101, 12474.313912734886, 12726.490940362282, 12786.041046921331, 10347.482625868826, 10471.528781204712, 10741.946290268812, 10895.237333386587, 11241.55033798639, 11679.883201168219, 11714.765704685688, 12210.75578488443, 12218.938905305416, 12440.68898277514, 11066.723331916444, 11289.830652540055, 11303.547858085913, 11844.466998984894, 11847.466863991314, 9806.842494651166, 9885.209183265179, 10104.747381315527, 10191.286609937233, 10313.690589282334, 9333.626654933998, 9380.718578442658, 9383.249340052917, 9415.2467802577, 9427.528623568656], "value": 6168.143562087838, "unit": "score"}], "name": "dromaeo_css", "value": 6319.887526536406, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 125.0, 180.0, 128.0, 125.0, 122.0, 125.0, 150.0, 129.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 165.0, 162.0, 163.0, 162.0, 165.0, 165.0, 165.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 79.0, 105.0, 77.0, 78.0, 77.0, 76.0, 105.0, 77.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 115.0, 85.0, 86.0, 85.0, 96.0, 86.0, 85.0, 111.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 148.0, 150.0, 150.0, 148.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 98.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 58.0, 59.0, 60.0, 60.0, 61.0, 60.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 68.0, 67.0, 69.0, 68.0, 67.0, 68.0, 68.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 95.0, 86.0, 85.0, 85.0, 84.0, 84.0, 86.0, 85.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 145.0, 132.0, 136.0, 134.0, 133.0, 134.0, 221.0, 141.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 175.0, 177.0, 178.0, 177.0, 175.0, 176.0, 177.0, 177.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 71.0, 70.0, 67.0, 68.0, 69.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1552.6, "alertThreshold": 2.0}]} 09:28:22 INFO - Return code: 0 09:28:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:28:22 INFO - # TBPL SUCCESS # 09:28:22 INFO - Running post-action listener: _resource_record_post_action 09:28:22 INFO - [mozharness: 2017-01-07 17:28:22.536794Z] Finished run-tests step (success) 09:28:22 INFO - Running post-run listener: _resource_record_post_run 09:28:22 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 385105920; Read time: 8; Write time: 295420 09:28:22 INFO - TinderboxPrint: CPU usage
13.1% 09:28:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:28:22 INFO - TinderboxPrint: I/O write bytes / time
385,105,920 / 295,420 09:28:22 INFO - TinderboxPrint: CPU idle
8,338.0 (86.9%) 09:28:22 INFO - TinderboxPrint: CPU user
1,211.6 (12.6%) 09:28:22 INFO - TinderboxPrint: Swap in / out
0 / 0 09:28:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102567936; Read time: 0; Write time: 129572 09:28:22 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 281530368; Read time: 0; Write time: 165820 09:28:23 INFO - Running post-run listener: _upload_blobber_files 09:28:23 INFO - Blob upload gear active. 09:28:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:23 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 09:28:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:28:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:28:23 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:28:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:28:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:24 INFO - (blobuploader) - INFO - Done attempting. 09:28:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:28:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:28:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:29 INFO - (blobuploader) - INFO - Done attempting. 09:28:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:28:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:28:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:29 INFO - (blobuploader) - INFO - Done attempting. 09:28:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:28:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:28:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:30 INFO - (blobuploader) - INFO - Done attempting. 09:28:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:30 INFO - Return code: 0 09:28:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5d1a836728ffef9e97b46745af69e13c64c4d97c6310c9cd1bfdf6f708c7334775afc3b29f60e679bfc9f66bd5d76061fdcbe287533a35fb37dde56b2042f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff9128c726284350739aea4c80ccd1e8f0b70453fc3abc7f95d34dae7486768664746c95b5b4d77d00600b876253ebda68ad522ae5b2b02ff9567fa94b33c3ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/385bda7b768e9280093397709bdc98886786f28a4bc88ae2f59720d9e4bd3ab31cebebb2816e1acf0903b3c65e532f5e5b5240eb927bdbf7d472cf4cbf238c20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5683faa683c1eb9088cce9975d6e07fa0b57697ec9afe602aac43e7ce5e76bc9e3703a64f2089547f5dab19b0df62afd446e6ab8d276b51c660e32528ba112"} 09:28:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:30 INFO - Contents: 09:28:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5d1a836728ffef9e97b46745af69e13c64c4d97c6310c9cd1bfdf6f708c7334775afc3b29f60e679bfc9f66bd5d76061fdcbe287533a35fb37dde56b2042f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff9128c726284350739aea4c80ccd1e8f0b70453fc3abc7f95d34dae7486768664746c95b5b4d77d00600b876253ebda68ad522ae5b2b02ff9567fa94b33c3ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/385bda7b768e9280093397709bdc98886786f28a4bc88ae2f59720d9e4bd3ab31cebebb2816e1acf0903b3c65e532f5e5b5240eb927bdbf7d472cf4cbf238c20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5683faa683c1eb9088cce9975d6e07fa0b57697ec9afe602aac43e7ce5e76bc9e3703a64f2089547f5dab19b0df62afd446e6ab8d276b51c660e32528ba112"} 09:28:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:30 INFO - Copying logs to upload dir... 09:28:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.171772 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-07 09:28:29.223228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:28:29.226686) ========= 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=232498196343f89f466a4a9a000001c8-1483808861.122813-290819486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5d1a836728ffef9e97b46745af69e13c64c4d97c6310c9cd1bfdf6f708c7334775afc3b29f60e679bfc9f66bd5d76061fdcbe287533a35fb37dde56b2042f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff9128c726284350739aea4c80ccd1e8f0b70453fc3abc7f95d34dae7486768664746c95b5b4d77d00600b876253ebda68ad522ae5b2b02ff9567fa94b33c3ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/385bda7b768e9280093397709bdc98886786f28a4bc88ae2f59720d9e4bd3ab31cebebb2816e1acf0903b3c65e532f5e5b5240eb927bdbf7d472cf4cbf238c20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5683faa683c1eb9088cce9975d6e07fa0b57697ec9afe602aac43e7ce5e76bc9e3703a64f2089547f5dab19b0df62afd446e6ab8d276b51c660e32528ba112"} build_url:https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011679 build_url: 'https://queue.taskcluster.net/v1/task/CI46j_zNRhGjXNKIHRr94g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5d1a836728ffef9e97b46745af69e13c64c4d97c6310c9cd1bfdf6f708c7334775afc3b29f60e679bfc9f66bd5d76061fdcbe287533a35fb37dde56b2042f06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff9128c726284350739aea4c80ccd1e8f0b70453fc3abc7f95d34dae7486768664746c95b5b4d77d00600b876253ebda68ad522ae5b2b02ff9567fa94b33c3ef", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/385bda7b768e9280093397709bdc98886786f28a4bc88ae2f59720d9e4bd3ab31cebebb2816e1acf0903b3c65e532f5e5b5240eb927bdbf7d472cf4cbf238c20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc5683faa683c1eb9088cce9975d6e07fa0b57697ec9afe602aac43e7ce5e76bc9e3703a64f2089547f5dab19b0df62afd446e6ab8d276b51c660e32528ba112"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:28:29.252801) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:28:29.253140) ========= 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=232498196343f89f466a4a9a000001c8-1483808861.122813-290819486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:28:29.335094) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:28:29.335433) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:28:29.335719) ========= ========= Total master_lag: 0.15 =========