builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1493999784.16 results: success (0) revision: 23fe0b76a018a5077a0f7234cff91c41e4b6af64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.156240) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.156754) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.157036) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.172595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.172972) ========= 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=826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-05 08:56:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 86.3M=0s 2017-05-05 08:56:24 (86.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119850 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.310257) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.310581) ========= 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=826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016400 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:24.359931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 08:56:24.363340) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 23fe0b76a018a5077a0f7234cff91c41e4b6af64 --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 23fe0b76a018a5077a0f7234cff91c41e4b6af64 --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=826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-05 08:56:24,516 truncating revision to first 12 chars 2017-05-05 08:56:24,516 Setting DEBUG logging. 2017-05-05 08:56:24,516 attempt 1/10 2017-05-05 08:56:24,516 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/23fe0b76a018?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 08:56:25,636 unpacking tar archive at: mozilla-central-23fe0b76a018/testing/mozharness/ program finished with exit code 0 elapsedTime=1.429048 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 08:56:25.813319) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:25.813670) ========= script_repo_revision: 23fe0b76a018a5077a0f7234cff91c41e4b6af64 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:25.814041) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:25.814635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 08:56:25.831615) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-05 08:56:25.831954) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:56:26 INFO - MultiFileLogger online at 20170505 08:56:26 in /builds/slave/test 08:56:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:56:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:56:26 INFO - {'append_to_log': False, 08:56:26 INFO - 'base_work_dir': '/builds/slave/test', 08:56:26 INFO - 'blob_upload_branch': 'Firefox', 08:56:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:56:26 INFO - 'branch': 'Firefox', 08:56:26 INFO - 'buildbot_json_path': 'buildprops.json', 08:56:26 INFO - 'config_files': ('talos/linux_config.py',), 08:56:26 INFO - 'default_actions': ('clobber', 08:56:26 INFO - 'read-buildbot-config', 08:56:26 INFO - 'download-and-extract', 08:56:26 INFO - 'populate-webroot', 08:56:26 INFO - 'create-virtualenv', 08:56:26 INFO - 'install', 08:56:26 INFO - 'run-tests'), 08:56:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:56:26 INFO - 'download_minidump_stackwalk': True, 08:56:26 INFO - 'download_symbols': 'ondemand', 08:56:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:56:26 INFO - 'tooltool.py': '/tools/tooltool.py', 08:56:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:56:26 INFO - '/tools/misc-python/virtualenv.py')}, 08:56:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:56:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:56:26 INFO - 'gecko_profile': False, 08:56:26 INFO - 'gecko_profile_interval': 0, 08:56:26 INFO - 'installer_path': 'installer.exe', 08:56:26 INFO - 'log_level': 'info', 08:56:26 INFO - 'log_name': 'talos', 08:56:26 INFO - 'log_to_console': True, 08:56:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:56:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:56:26 INFO - 'opt_config_files': (), 08:56:26 INFO - 'pip_index': False, 08:56:26 INFO - 'suite': 'dromaeojs', 08:56:26 INFO - 'system_bits': '32', 08:56:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:56:26 INFO - 'title': 'talos-linux64-ix-052', 08:56:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:56:26 INFO - 'use_talos_json': True, 08:56:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:56:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:56:26 INFO - 'work_dir': 'build'} 08:56:26 INFO - [mozharness: 2017-05-05 15:56:26.087254Z] Running clobber step. 08:56:26 INFO - Running pre-action listener: _resource_record_pre_action 08:56:26 INFO - Running main action method: clobber 08:56:26 INFO - rmtree: /builds/slave/test/build 08:56:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:56:27 INFO - Running post-action listener: _resource_record_post_action 08:56:27 INFO - [mozharness: 2017-05-05 15:56:27.054998Z] Finished clobber step (success) 08:56:27 INFO - [mozharness: 2017-05-05 15:56:27.055101Z] Running read-buildbot-config step. 08:56:27 INFO - Running pre-action listener: _resource_record_pre_action 08:56:27 INFO - Running main action method: read_buildbot_config 08:56:27 INFO - Using buildbot properties: 08:56:27 INFO - { 08:56:27 INFO - "project": "", 08:56:27 INFO - "product": "firefox", 08:56:27 INFO - "who": "ffxbld@noreply.mozilla.org", 08:56:27 INFO - "installer_path": "public/build/target.tar.bz2", 08:56:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:56:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:56:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:56:27 INFO - "stage_platform": "linux64-pgo", 08:56:27 INFO - "basedir": "/builds/slave/test", 08:56:27 INFO - "buildnumber": 166, 08:56:27 INFO - "platform": "ubuntu64_hw", 08:56:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:56:27 INFO - "slavebuilddir": "test", 08:56:27 INFO - "taskId": "MSDoZZNFSOqgfviVzlz7Cg", 08:56:27 INFO - "branch": "mozilla-central", 08:56:27 INFO - "script_repo_revision": "production", 08:56:27 INFO - "revision": "23fe0b76a018a5077a0f7234cff91c41e4b6af64", 08:56:27 INFO - "slavename": "talos-linux64-ix-052", 08:56:27 INFO - "repo_path": "mozilla-central" 08:56:27 INFO - } 08:56:27 INFO - Finding installer, test and symbols from parent task. 08:56:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MSDoZZNFSOqgfviVzlz7Cg'}, attempt #1 08:56:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MSDoZZNFSOqgfviVzlz7Cg'}, attempt #1 08:56:27 INFO - Task dependencies: CHjEs6ExRWqh7F70WG6u8w 08:56:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w'}, attempt #1 08:56:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2 08:56:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/test_packages.json 08:56:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.crashreporter-symbols.zip 08:56:28 INFO - Running post-action listener: _resource_record_post_action 08:56:28 INFO - [mozharness: 2017-05-05 15:56:28.419259Z] Finished read-buildbot-config step (success) 08:56:28 INFO - [mozharness: 2017-05-05 15:56:28.419479Z] Running download-and-extract step. 08:56:28 INFO - Running pre-action listener: _resource_record_pre_action 08:56:28 INFO - Running main action method: download_and_extract 08:56:28 INFO - mkdir: /builds/slave/test/build/tests 08:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:28 INFO - trying https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.test_packages.json 08:56:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:56:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:56:28 INFO - Downloaded 986 bytes. 08:56:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:56:28 INFO - Using the following test package requirements: 08:56:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:56:28 INFO - u'common': [u'target.common.tests.zip'], 08:56:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:56:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:56:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:56:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:56:28 INFO - u'mozbase': [u'target.common.tests.zip'], 08:56:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:56:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:56:28 INFO - u'web-platform': [u'target.common.tests.zip', 08:56:28 INFO - u'target.web-platform.tests.zip'], 08:56:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:56:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:56:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.common.tests.zip 08:56:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:56:28 INFO - Fetch https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.common.tests.zip into memory 08:56:30 INFO - Content-Length response header: 60565825 08:56:30 INFO - Bytes received: 60565825 08:56:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:56:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.common.tests.zip 08:56:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:56:39 INFO - Fetch https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.common.tests.zip into memory 08:56:41 INFO - Content-Length response header: 60565825 08:56:41 INFO - Bytes received: 60565825 08:56:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.talos.tests.zip 08:56:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:56:52 INFO - Fetch https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.talos.tests.zip into memory 08:56:52 INFO - Content-Length response header: 14408697 08:56:52 INFO - Bytes received: 14408697 08:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:53 INFO - trying https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2 08:56:53 INFO - Downloading https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:56:54 INFO - Downloaded 64059137 bytes. 08:56:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2 08:56:54 INFO - mkdir: /builds/slave/test/properties 08:56:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:56:54 INFO - Writing to file /builds/slave/test/properties/build_url 08:56:54 INFO - Contents: 08:56:54 INFO - build_url:https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2 08:56:54 INFO - Running post-action listener: _resource_record_post_action 08:56:54 INFO - Running post-action listener: set_extra_try_arguments 08:56:54 INFO - [mozharness: 2017-05-05 15:56:54.214018Z] Finished download-and-extract step (success) 08:56:54 INFO - [mozharness: 2017-05-05 15:56:54.214117Z] Running populate-webroot step. 08:56:54 INFO - Running pre-action listener: _resource_record_pre_action 08:56:54 INFO - Running main action method: populate_webroot 08:56:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:56:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:56:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:56:54 INFO - '--tppagecycles', 08:56:54 INFO - '7'], 08:56:54 INFO - 'tests': ['tsvgm']}}, 08:56:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:56:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:56:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:56:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:56:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:56:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['damp', 'tps']}, 08:56:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'tests': ['damp', 'tps']}, 08:56:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['dromaeo_dom']}, 08:56:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:56:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:56:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:56:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['a11yr', 08:56:54 INFO - 'ts_paint', 08:56:54 INFO - 'tpaint', 08:56:54 INFO - 'sessionrestore', 08:56:54 INFO - 'sessionrestore_no_auto_restore', 08:56:54 INFO - 'tabpaint']}, 08:56:54 INFO - 'other-e10s': {'tests': ['a11yr', 08:56:54 INFO - 'ts_paint', 08:56:54 INFO - 'tpaint', 08:56:54 INFO - 'sessionrestore', 08:56:54 INFO - 'sessionrestore_no_auto_restore', 08:56:54 INFO - 'tabpaint']}, 08:56:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:56:54 INFO - 'ts_paint', 08:56:54 INFO - 'tpaint', 08:56:54 INFO - 'sessionrestore', 08:56:54 INFO - 'sessionrestore_no_auto_restore', 08:56:54 INFO - 'tabpaint']}, 08:56:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:56:54 INFO - 'ts_paint', 08:56:54 INFO - 'tpaint', 08:56:54 INFO - 'sessionrestore', 08:56:54 INFO - 'sessionrestore_no_auto_restore', 08:56:54 INFO - 'tabpaint']}, 08:56:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['a11yr', 08:56:54 INFO - 'ts_paint', 08:56:54 INFO - 'tpaint', 08:56:54 INFO - 'sessionrestore', 08:56:54 INFO - 'sessionrestore_no_auto_restore', 08:56:54 INFO - 'tabpaint']}, 08:56:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['a11yr', 08:56:54 INFO - 'ts_paint', 08:56:54 INFO - 'tpaint', 08:56:54 INFO - 'sessionrestore', 08:56:54 INFO - 'sessionrestore_no_auto_restore', 08:56:54 INFO - 'tabpaint']}, 08:56:54 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:56:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:56:54 INFO - 'bloom_basic_ref']}, 08:56:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['tsvgx', 08:56:54 INFO - 'tsvgr_opacity', 08:56:54 INFO - 'tart', 08:56:54 INFO - 'tscrollx', 08:56:54 INFO - 'cart', 08:56:54 INFO - 'tsvg_static']}, 08:56:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:56:54 INFO - 'tsvgr_opacity', 08:56:54 INFO - 'tart', 08:56:54 INFO - 'tscrollx', 08:56:54 INFO - 'cart', 08:56:54 INFO - 'tsvg_static']}, 08:56:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'talos_options': ['--disable-e10s'], 08:56:54 INFO - 'tests': ['tp5o']}, 08:56:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:56:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'talos_options': ['--disable-e10s', 08:56:54 INFO - '--xperf_path', 08:56:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:54 INFO - 'tests': ['tp5n']}, 08:56:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:56:54 INFO - 'talos_options': ['--xperf_path', 08:56:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:56:54 INFO - 'tests': ['tp5n']}}, 08:56:54 INFO - 'talos.zip': {'path': '', 08:56:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:56:54 INFO - Running post-action listener: _resource_record_post_action 08:56:54 INFO - [mozharness: 2017-05-05 15:56:54.227921Z] Finished populate-webroot step (success) 08:56:54 INFO - [mozharness: 2017-05-05 15:56:54.228011Z] Running create-virtualenv step. 08:56:54 INFO - Running pre-action listener: _resource_record_pre_action 08:56:54 INFO - Running main action method: create_virtualenv 08:56:54 INFO - Creating virtualenv /builds/slave/test/build/venv 08:56:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:56:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:56:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:56:54 INFO - Using real prefix '/usr' 08:56:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:56:54 INFO - Installing distribute.............................................................................................................................................................................................done. 08:56:56 INFO - Installing pip.................done. 08:56:56 INFO - Return code: 0 08:56:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:56:57 INFO - Reading from file tmpfile_stdout 08:56:57 INFO - Output received: 08:56:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:56:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:56:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:56:57 INFO - Using env: {'DISPLAY': ':0', 08:56:57 INFO - 'HOME': '/home/cltbld', 08:56:57 INFO - 'LANG': 'en_US.UTF-8', 08:56:57 INFO - 'LANGUAGE': 'en_US:en', 08:56:57 INFO - 'LOGNAME': 'cltbld', 08:56:57 INFO - 'MAIL': '/var/mail/cltbld', 08:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:57 INFO - 'MOZ_NO_REMOTE': '1', 08:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:56:57 INFO - 'NO_EM_RESTART': '1', 08:56:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:57 INFO - 'PWD': '/builds/slave/test', 08:56:57 INFO - 'SHELL': '/bin/bash', 08:56:57 INFO - 'SHLVL': '1', 08:56:57 INFO - 'TERM': 'linux', 08:56:57 INFO - 'TMOUT': '86400', 08:56:57 INFO - 'USER': 'cltbld', 08:56:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:57 INFO - '_': '/tools/buildbot/bin/python'} 08:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:56:57 INFO - Cleaning up... 08:56:57 INFO - Return code: 0 08:56:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:56:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:56:57 INFO - Using env: {'DISPLAY': ':0', 08:56:57 INFO - 'HOME': '/home/cltbld', 08:56:57 INFO - 'LANG': 'en_US.UTF-8', 08:56:57 INFO - 'LANGUAGE': 'en_US:en', 08:56:57 INFO - 'LOGNAME': 'cltbld', 08:56:57 INFO - 'MAIL': '/var/mail/cltbld', 08:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:57 INFO - 'MOZ_NO_REMOTE': '1', 08:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:56:57 INFO - 'NO_EM_RESTART': '1', 08:56:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:57 INFO - 'PWD': '/builds/slave/test', 08:56:57 INFO - 'SHELL': '/bin/bash', 08:56:57 INFO - 'SHLVL': '1', 08:56:57 INFO - 'TERM': 'linux', 08:56:57 INFO - 'TMOUT': '86400', 08:56:57 INFO - 'USER': 'cltbld', 08:56:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:57 INFO - '_': '/tools/buildbot/bin/python'} 08:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:57 INFO - Downloading/unpacking psutil>=3.1.1 08:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:56:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:58 INFO - Installing collected packages: psutil 08:56:58 INFO - Running setup.py install for psutil 08:56:58 INFO - building 'psutil._psutil_linux' extension 08:56:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:56:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:56:58 INFO - building 'psutil._psutil_posix' extension 08:56:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:56:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:56:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:56:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:56:59 INFO - Successfully installed psutil 08:56:59 INFO - Cleaning up... 08:56:59 INFO - Return code: 0 08:56:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:59 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 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:56:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:56:59 INFO - Using env: {'DISPLAY': ':0', 08:56:59 INFO - 'HOME': '/home/cltbld', 08:56:59 INFO - 'LANG': 'en_US.UTF-8', 08:56:59 INFO - 'LANGUAGE': 'en_US:en', 08:56:59 INFO - 'LOGNAME': 'cltbld', 08:56:59 INFO - 'MAIL': '/var/mail/cltbld', 08:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:59 INFO - 'MOZ_NO_REMOTE': '1', 08:56:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:56:59 INFO - 'NO_EM_RESTART': '1', 08:56:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:59 INFO - 'PWD': '/builds/slave/test', 08:56:59 INFO - 'SHELL': '/bin/bash', 08:56:59 INFO - 'SHLVL': '1', 08:56:59 INFO - 'TERM': 'linux', 08:56:59 INFO - 'TMOUT': '86400', 08:56:59 INFO - 'USER': 'cltbld', 08:56:59 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:59 INFO - '_': '/tools/buildbot/bin/python'} 08:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:56:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:56:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:57:00 INFO - Installing collected packages: mozsystemmonitor 08:57:00 INFO - Running setup.py install for mozsystemmonitor 08:57:00 INFO - Successfully installed mozsystemmonitor 08:57:00 INFO - Cleaning up... 08:57:00 INFO - Return code: 0 08:57:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:57: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')]} 08:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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', '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 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:57: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 jsonschema==2.5.1 08:57:00 INFO - Using env: {'DISPLAY': ':0', 08:57:00 INFO - 'HOME': '/home/cltbld', 08:57:00 INFO - 'LANG': 'en_US.UTF-8', 08:57:00 INFO - 'LANGUAGE': 'en_US:en', 08:57:00 INFO - 'LOGNAME': 'cltbld', 08:57:00 INFO - 'MAIL': '/var/mail/cltbld', 08:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:00 INFO - 'MOZ_NO_REMOTE': '1', 08:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:00 INFO - 'NO_EM_RESTART': '1', 08:57:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:00 INFO - 'PWD': '/builds/slave/test', 08:57:00 INFO - 'SHELL': '/bin/bash', 08:57:00 INFO - 'SHLVL': '1', 08:57:00 INFO - 'TERM': 'linux', 08:57:00 INFO - 'TMOUT': '86400', 08:57:00 INFO - 'USER': 'cltbld', 08:57:00 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:00 INFO - '_': '/tools/buildbot/bin/python'} 08:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:00 INFO - Downloading/unpacking jsonschema==2.5.1 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:57:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:02 INFO - Downloading functools32-3.2.3-2.tar.gz 08:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:57:02 INFO - warning: no files found matching '*.txt' 08:57:02 INFO - no previously-included directories found matching 'build' 08:57:02 INFO - no previously-included directories found matching 'dist' 08:57:02 INFO - no previously-included directories found matching '.git*' 08:57:02 INFO - Installing collected packages: jsonschema, functools32 08:57:02 INFO - Running setup.py install for functools32 08:57:02 INFO - warning: no files found matching '*.txt' 08:57:02 INFO - no previously-included directories found matching 'build' 08:57:02 INFO - no previously-included directories found matching 'dist' 08:57:02 INFO - no previously-included directories found matching '.git*' 08:57:02 INFO - Successfully installed jsonschema functools32 08:57:02 INFO - Cleaning up... 08:57:02 INFO - Return code: 0 08:57:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:57:02 INFO - Using env: {'DISPLAY': ':0', 08:57:02 INFO - 'HOME': '/home/cltbld', 08:57:02 INFO - 'LANG': 'en_US.UTF-8', 08:57:02 INFO - 'LANGUAGE': 'en_US:en', 08:57:02 INFO - 'LOGNAME': 'cltbld', 08:57:02 INFO - 'MAIL': '/var/mail/cltbld', 08:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:02 INFO - 'MOZ_NO_REMOTE': '1', 08:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:02 INFO - 'NO_EM_RESTART': '1', 08:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:02 INFO - 'PWD': '/builds/slave/test', 08:57:02 INFO - 'SHELL': '/bin/bash', 08:57:02 INFO - 'SHLVL': '1', 08:57:02 INFO - 'TERM': 'linux', 08:57:02 INFO - 'TMOUT': '86400', 08:57:02 INFO - 'USER': 'cltbld', 08:57:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:02 INFO - '_': '/tools/buildbot/bin/python'} 08:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:57:02 INFO - Cleaning up... 08:57:02 INFO - Return code: 0 08:57:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:57:02 INFO - Using env: {'DISPLAY': ':0', 08:57:02 INFO - 'HOME': '/home/cltbld', 08:57:02 INFO - 'LANG': 'en_US.UTF-8', 08:57:02 INFO - 'LANGUAGE': 'en_US:en', 08:57:02 INFO - 'LOGNAME': 'cltbld', 08:57:02 INFO - 'MAIL': '/var/mail/cltbld', 08:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:02 INFO - 'MOZ_NO_REMOTE': '1', 08:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:02 INFO - 'NO_EM_RESTART': '1', 08:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:02 INFO - 'PWD': '/builds/slave/test', 08:57:02 INFO - 'SHELL': '/bin/bash', 08:57:02 INFO - 'SHLVL': '1', 08:57:02 INFO - 'TERM': 'linux', 08:57:02 INFO - 'TMOUT': '86400', 08:57:02 INFO - 'USER': 'cltbld', 08:57:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:02 INFO - '_': '/tools/buildbot/bin/python'} 08:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:02 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:57:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:57:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:04 INFO - Downloading docopt-0.6.1.tar.gz 08:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:57:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:57:04 INFO - Running setup.py install for blobuploader 08:57:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:57:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:57:04 INFO - Running setup.py install for requests 08:57:04 INFO - Running setup.py install for docopt 08:57:05 INFO - Successfully installed blobuploader requests docopt 08:57:05 INFO - Cleaning up... 08:57:05 INFO - Return code: 0 08:57:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:05 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 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:05 INFO - Using env: {'DISPLAY': ':0', 08:57:05 INFO - 'HOME': '/home/cltbld', 08:57:05 INFO - 'LANG': 'en_US.UTF-8', 08:57:05 INFO - 'LANGUAGE': 'en_US:en', 08:57:05 INFO - 'LOGNAME': 'cltbld', 08:57:05 INFO - 'MAIL': '/var/mail/cltbld', 08:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:05 INFO - 'MOZ_NO_REMOTE': '1', 08:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:05 INFO - 'NO_EM_RESTART': '1', 08:57:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:05 INFO - 'PWD': '/builds/slave/test', 08:57:05 INFO - 'SHELL': '/bin/bash', 08:57:05 INFO - 'SHLVL': '1', 08:57:05 INFO - 'TERM': 'linux', 08:57:05 INFO - 'TMOUT': '86400', 08:57:05 INFO - 'USER': 'cltbld', 08:57:05 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:05 INFO - '_': '/tools/buildbot/bin/python'} 08:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:05 INFO - Running setup.py (path:/tmp/pip-iEwoMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:05 INFO - Running setup.py (path:/tmp/pip-g_FMzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:05 INFO - Running setup.py (path:/tmp/pip-h9uqnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:05 INFO - Running setup.py (path:/tmp/pip-E2jUK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:05 INFO - Running setup.py (path:/tmp/pip-u4tYcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:05 INFO - Running setup.py (path:/tmp/pip-A9xUzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:05 INFO - Running setup.py (path:/tmp/pip-fQJGrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:05 INFO - Running setup.py (path:/tmp/pip-AxxdEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:05 INFO - Running setup.py (path:/tmp/pip-6KwArp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:05 INFO - Running setup.py (path:/tmp/pip-bboKme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:05 INFO - Running setup.py (path:/tmp/pip-5YJp3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:06 INFO - Running setup.py (path:/tmp/pip-K8vq_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:06 INFO - Running setup.py (path:/tmp/pip-TMj33u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:06 INFO - Running setup.py (path:/tmp/pip-swiMzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:06 INFO - Running setup.py (path:/tmp/pip-F01Ir7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:06 INFO - Running setup.py (path:/tmp/pip-p7Vc9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:06 INFO - Running setup.py (path:/tmp/pip-coCFMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:57:06 INFO - Running setup.py install for manifestparser 08:57:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:57:06 INFO - Running setup.py install for mozcrash 08:57:06 INFO - Running setup.py install for mozdebug 08:57:06 INFO - Running setup.py install for mozdevice 08:57:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:57:06 INFO - Running setup.py install for mozfile 08:57:06 INFO - Running setup.py install for mozhttpd 08:57:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for mozinfo 08:57:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for mozInstall 08:57:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for mozleak 08:57:07 INFO - Running setup.py install for mozlog 08:57:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for moznetwork 08:57:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for mozprocess 08:57:07 INFO - Running setup.py install for mozprofile 08:57:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for mozrunner 08:57:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:07 INFO - Running setup.py install for mozscreenshot 08:57:08 INFO - Running setup.py install for moztest 08:57:08 INFO - Running setup.py install for mozversion 08:57:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:08 INFO - Cleaning up... 08:57:08 INFO - Return code: 0 08:57:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57: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')]} 08:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57: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')]} 08:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:08 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 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:08 INFO - Using env: {'DISPLAY': ':0', 08:57:08 INFO - 'HOME': '/home/cltbld', 08:57:08 INFO - 'LANG': 'en_US.UTF-8', 08:57:08 INFO - 'LANGUAGE': 'en_US:en', 08:57:08 INFO - 'LOGNAME': 'cltbld', 08:57:08 INFO - 'MAIL': '/var/mail/cltbld', 08:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:08 INFO - 'MOZ_NO_REMOTE': '1', 08:57:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:08 INFO - 'NO_EM_RESTART': '1', 08:57:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:08 INFO - 'PWD': '/builds/slave/test', 08:57:08 INFO - 'SHELL': '/bin/bash', 08:57:08 INFO - 'SHLVL': '1', 08:57:08 INFO - 'TERM': 'linux', 08:57:08 INFO - 'TMOUT': '86400', 08:57:08 INFO - 'USER': 'cltbld', 08:57:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:08 INFO - '_': '/tools/buildbot/bin/python'} 08:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:08 INFO - Running setup.py (path:/tmp/pip-QWq3qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:08 INFO - Running setup.py (path:/tmp/pip-9vTV1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:08 INFO - Running setup.py (path:/tmp/pip-9tvRbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:08 INFO - Running setup.py (path:/tmp/pip-KvjcVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:08 INFO - Running setup.py (path:/tmp/pip-e6SrCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:08 INFO - Running setup.py (path:/tmp/pip-rfsgwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:08 INFO - Running setup.py (path:/tmp/pip-dPrVk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:08 INFO - Running setup.py (path:/tmp/pip-7hOG4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:09 INFO - Running setup.py (path:/tmp/pip-85dQ2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:09 INFO - Running setup.py (path:/tmp/pip-IkbS_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:09 INFO - Running setup.py (path:/tmp/pip-jCMNZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:09 INFO - Running setup.py (path:/tmp/pip-XkyPMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:09 INFO - Running setup.py (path:/tmp/pip-8Syw9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:09 INFO - Running setup.py (path:/tmp/pip-MWmAdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:09 INFO - Running setup.py (path:/tmp/pip-8jDUkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:09 INFO - Running setup.py (path:/tmp/pip-uREjjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:09 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)) 08:57:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:09 INFO - Running setup.py (path:/tmp/pip-WKqzoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57: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 08:57:10 INFO - Downloading blessings-1.6.tar.gz 08:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:57:10 INFO - Installing collected packages: blessings 08:57:10 INFO - Running setup.py install for blessings 08:57:10 INFO - Successfully installed blessings 08:57:10 INFO - Cleaning up... 08:57:10 INFO - Return code: 0 08:57:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:57:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:11 INFO - Reading from file tmpfile_stdout 08:57:11 INFO - Current package versions: 08:57:11 INFO - argparse == 1.2.1 08:57:11 INFO - blessings == 1.6 08:57:11 INFO - blobuploader == 1.2.4 08:57:11 INFO - docopt == 0.6.1 08:57:11 INFO - functools32 == 3.2.3-2 08:57:11 INFO - jsonschema == 2.5.1 08:57:11 INFO - manifestparser == 1.1 08:57:11 INFO - mozInstall == 1.12 08:57:11 INFO - mozcrash == 1.0 08:57:11 INFO - mozdebug == 0.1 08:57:11 INFO - mozdevice == 0.50 08:57:11 INFO - mozfile == 1.2 08:57:11 INFO - mozhttpd == 0.7 08:57:11 INFO - mozinfo == 0.9 08:57:11 INFO - mozleak == 0.1 08:57:11 INFO - mozlog == 3.4 08:57:11 INFO - moznetwork == 0.27 08:57:11 INFO - mozprocess == 0.25 08:57:11 INFO - mozprofile == 0.28 08:57:11 INFO - mozrunner == 6.13 08:57:11 INFO - mozscreenshot == 0.1 08:57:11 INFO - mozsystemmonitor == 0.3 08:57:11 INFO - moztest == 0.8 08:57:11 INFO - mozversion == 1.4 08:57:11 INFO - psutil == 3.1.1 08:57:11 INFO - requests == 1.2.3 08:57:11 INFO - wsgiref == 0.1.2 08:57:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:11 INFO - Using env: {'DISPLAY': ':0', 08:57:11 INFO - 'HOME': '/home/cltbld', 08:57:11 INFO - 'LANG': 'en_US.UTF-8', 08:57:11 INFO - 'LANGUAGE': 'en_US:en', 08:57:11 INFO - 'LOGNAME': 'cltbld', 08:57:11 INFO - 'MAIL': '/var/mail/cltbld', 08:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:11 INFO - 'MOZ_NO_REMOTE': '1', 08:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:11 INFO - 'NO_EM_RESTART': '1', 08:57:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:11 INFO - 'PWD': '/builds/slave/test', 08:57:11 INFO - 'SHELL': '/bin/bash', 08:57:11 INFO - 'SHLVL': '1', 08:57:11 INFO - 'TERM': 'linux', 08:57:11 INFO - 'TMOUT': '86400', 08:57:11 INFO - 'USER': 'cltbld', 08:57:11 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:11 INFO - '_': '/tools/buildbot/bin/python'} 08:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:57:11 INFO - Cleaning up... 08:57:11 INFO - Return code: 0 08:57:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x156a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1808310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, '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': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:57:11 INFO - Using env: {'DISPLAY': ':0', 08:57:11 INFO - 'HOME': '/home/cltbld', 08:57:11 INFO - 'LANG': 'en_US.UTF-8', 08:57:11 INFO - 'LANGUAGE': 'en_US:en', 08:57:11 INFO - 'LOGNAME': 'cltbld', 08:57:11 INFO - 'MAIL': '/var/mail/cltbld', 08:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:11 INFO - 'MOZ_NO_REMOTE': '1', 08:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:11 INFO - 'NO_EM_RESTART': '1', 08:57:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:11 INFO - 'PWD': '/builds/slave/test', 08:57:11 INFO - 'SHELL': '/bin/bash', 08:57:11 INFO - 'SHLVL': '1', 08:57:11 INFO - 'TERM': 'linux', 08:57:11 INFO - 'TMOUT': '86400', 08:57:11 INFO - 'USER': 'cltbld', 08:57:11 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:11 INFO - '_': '/tools/buildbot/bin/python'} 08:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:57:11 INFO - Cleaning up... 08:57:11 INFO - Return code: 0 08:57:11 INFO - Running post-action listener: _resource_record_post_action 08:57:11 INFO - Running post-action listener: _start_resource_monitoring 08:57:11 INFO - Starting resource monitoring. 08:57:11 INFO - [mozharness: 2017-05-05 15:57:11.705987Z] Finished create-virtualenv step (success) 08:57:11 INFO - [mozharness: 2017-05-05 15:57:11.706459Z] Running install step. 08:57:11 INFO - Running pre-action listener: _resource_record_pre_action 08:57:11 INFO - Running main action method: install 08:57:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:11 INFO - Reading from file tmpfile_stdout 08:57:11 INFO - Detecting whether we're running mozinstall >=1.0... 08:57:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:57:11 INFO - Reading from file tmpfile_stdout 08:57:11 INFO - Output received: 08:57:11 INFO - Usage: mozinstall [options] installer 08:57:11 INFO - Options: 08:57:11 INFO - -h, --help show this help message and exit 08:57:11 INFO - -d DEST, --destination=DEST 08:57:11 INFO - Directory to install application into. [default: 08:57:11 INFO - "/builds/slave/test"] 08:57:11 INFO - --app=APP Application being installed. [default: firefox] 08:57:11 INFO - mkdir: /builds/slave/test/build/application 08:57:11 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'] 08:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:57:28 INFO - Reading from file tmpfile_stdout 08:57:28 INFO - Output received: 08:57:28 INFO - /builds/slave/test/build/application/firefox/firefox 08:57:28 INFO - Running post-action listener: _resource_record_post_action 08:57:28 INFO - [mozharness: 2017-05-05 15:57:28.576296Z] Finished install step (success) 08:57:28 INFO - [mozharness: 2017-05-05 15:57:28.576570Z] Running run-tests step. 08:57:28 INFO - Running pre-action listener: _resource_record_pre_action 08:57:28 INFO - Running main action method: run_tests 08:57:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:57:28 INFO - Python 2.7.3 08:57:28 INFO - Return code: 0 08:57:28 INFO - grabbing minidump binary from tooltool 08:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:28 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 0x1805400>, '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 0x18063b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1806840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:57:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:57:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:57:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:57:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:57:28 INFO - Return code: 0 08:57:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:57:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:57:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:57:28 INFO - ENV: RUST_BACKTRACE is now 1 08:57:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:57:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:57:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:57:28 INFO - Using env: {'DISPLAY': ':0', 08:57:28 INFO - 'HOME': '/home/cltbld', 08:57:28 INFO - 'LANG': 'en_US.UTF-8', 08:57:28 INFO - 'LANGUAGE': 'en_US:en', 08:57:28 INFO - 'LOGNAME': 'cltbld', 08:57:28 INFO - 'MAIL': '/var/mail/cltbld', 08:57:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:57:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:28 INFO - 'MOZ_NO_REMOTE': '1', 08:57:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:28 INFO - 'NO_EM_RESTART': '1', 08:57:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:28 INFO - 'PWD': '/builds/slave/test', 08:57:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:57:28 INFO - 'RUST_BACKTRACE': '1', 08:57:28 INFO - 'SHELL': '/bin/bash', 08:57:28 INFO - 'SHLVL': '1', 08:57:28 INFO - 'TERM': 'linux', 08:57:28 INFO - 'TMOUT': '86400', 08:57:28 INFO - 'USER': 'cltbld', 08:57:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605', 08:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:28 INFO - '_': '/tools/buildbot/bin/python'} 08:57:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:57:28 INFO - mozversion application_buildid: 20170505150510 08:57:28 INFO - mozversion application_changeset: 23fe0b76a018a5077a0f7234cff91c41e4b6af64 08:57:28 INFO - mozversion application_display_name: Nightly 08:57:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:57:28 INFO - mozversion application_name: Firefox 08:57:28 INFO - mozversion application_remotingname: firefox 08:57:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:57:28 INFO - mozversion application_vendor: Mozilla 08:57:28 INFO - mozversion application_version: 55.0a1 08:57:28 INFO - mozversion platform_buildid: 20170505150510 08:57:28 INFO - mozversion platform_changeset: 23fe0b76a018a5077a0f7234cff91c41e4b6af64 08:57:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:57:28 INFO - mozversion platform_version: 55.0a1 08:57:28 INFO - using testdate: 1493999848 08:57:28 INFO - actual date: 1493999848 08:57:28 INFO - starting webserver on 'localhost:46459' 08:57:28 INFO - SUITE-START | Running 2 tests 08:57:28 INFO - TEST-START | dromaeo_css 08:57:28 INFO - operating with platform_type : linux_ 08:57:28 INFO - Initialising browser for dromaeo_css test... 08:57:28 INFO - TEST-INFO | started process 5231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKGtqil/profile http://localhost:46459/getInfo.html) 08:57:32 INFO - PID 5231 | __metrics Screen width/height:1600/1200 08:57:32 INFO - PID 5231 | colorDepth:24 08:57:32 INFO - PID 5231 | Browser inner width/height: 1024/695 08:57:32 INFO - PID 5231 | __metrics 08:57:33 INFO - PID 5231 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:57:33 INFO - PID 5231 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:57:34 INFO - TEST-INFO | 5231: exit 0 08:57:34 INFO - Browser initialized. 08:57:34 INFO - Running cycle 1/1 for dromaeo_css test... 08:57:34 INFO - TEST-INFO | started process 5322 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKGtqil/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:46 INFO - PID 5322 | RSS: Main: 149917696 08:57:46 INFO - PID 5322 | 09:00:49 INFO - PID 5322 | Cycle 1(1): loaded http://localhost:46459/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46459/tests/dromaeo/cssquery-ext.html) 09:00:50 INFO - PID 5322 | RSS: Main: 297234432 09:00:50 INFO - PID 5322 | 09:03:52 INFO - PID 5322 | Cycle 1(1): loaded http://localhost:46459/tests/dromaeo/cssquery-ext.html (next: http://localhost:46459/tests/dromaeo/cssquery-jquery.html) 09:03:53 INFO - PID 5322 | RSS: Main: 224362496 09:03:53 INFO - PID 5322 | 09:06:54 INFO - PID 5322 | Cycle 1(1): loaded http://localhost:46459/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46459/tests/dromaeo/cssquery-mootools.html) 09:06:55 INFO - PID 5322 | RSS: Main: 210657280 09:06:55 INFO - PID 5322 | 09:09:57 INFO - PID 5322 | Cycle 1(1): loaded http://localhost:46459/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46459/tests/dromaeo/cssquery-prototype.html) 09:09:57 INFO - PID 5322 | RSS: Main: 176267264 09:09:57 INFO - PID 5322 | 09:13:00 INFO - PID 5322 | Cycle 1(1): loaded http://localhost:46459/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46459/tests/dromaeo/cssquery-yui.html) 09:13:00 INFO - PID 5322 | RSS: Main: 176136192 09:13:00 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | Cycle 1(1): loaded http://localhost:46459/tests/dromaeo/cssquery-yui.html (next: http://localhost:46459/tests/dromaeo/cssquery-dojo.html) 09:16:02 INFO - PID 5322 | RSS: Main: 270012416 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | __start_tp_report 09:16:02 INFO - PID 5322 | _x_x_mozilla_page_load 09:16:02 INFO - PID 5322 | _x_x_mozilla_page_load_details 09:16:02 INFO - PID 5322 | |i|pagename|runs| 09:16:02 INFO - PID 5322 | |0;dojo.html;3385.6952874241315;3388.559487266655;3405.9489107663376;3408.130926613714;3451.1889705919107;5734.4265573442635;5867.76528938843;5871.442212989765;5915.733791979355;5927.911081333778;5875.206847075643;5901.645901245928;5921.319048309435;5969.432903874136;6002.459778619924;4843.58829499493;5115.9744201278945;5185.014847179043;5207.401148867882;5291.391489978644;5251.186066159732;5254.8686282842855;5323.361196656388;5325.893482130355;5327;5118.078745825768;5168.948310516885;5208.947910520922;5263.131583288753;5273.525382715536;5209.0363282792805;5215.452377500384;5231.659942103789;5232.2674825524455;5243.711595862226;5121.487851214849;5139.331886854722;5143.5113664201845;5155.484451554852;5163.122269214243;5809.912851307234;5829.242198514166;5832.970835145839;5834.708264586796;5870;6139.1712118863825;6246.000639897595;6248.250209974786;6389.105525226472;6409.262934762515;6484.513661475408;6484.546081774278;6485.286618471964;6495.220573531159;6503.219613646345;5913.970430147821;6021.488173505258;6029.216201893726;6033.125196846433;6037.335893051761;6403.423691867754;6480.125183100316;6509.837254068686;6577.079208910756;6599.901001484982;6129.186698318328;6219.906701399483;6236.750529978841;6333.36666333363;6335.65153916539;6402.647854368054;6411.96794016027;6416.7754128605275;6422.775202867971;6426.775062872777;6336.873262534724;6340.048992651047;6403.039544068334;6410.134631824737;6410.326915673881;6181.289151747516;6236.064590311399;6260.280067792171;6292.811215663537;6322.430981211713;4932.7286999215385;4933.3586633738105;4956.182229932021;4962.5533701967;4975.601951843843;4362.672799539983;4366.934495982563;4376.059147283349;4404.053128577371;4410.735355878657;5882.852928676732;5937.495312898452;6024.819255422344;6028.457438830526;6030.879382412327;6338.904916426257;6411.487081033506;6455.644939528277;6457.031445283245;6473.28793832678;6006.099085137177;6039.214902062704;6047.788327408608;6048.092786082123;6050.606710563799;5452.372977107683;5627.352854421794;5629.127485239795;5634.295713035871;5678.545716342683;6575.868482630416;6597.1423714916755;6726.831829204309;6734.528582999339;6740.62926539045;6461.967690161707;6472.482201423969;6478.28738838718;6483.027545868157;6483.286838447862;6670.7665231716655;6710.966445167743;6731.293214212633;6733.66331683404;6736.427403670743;6133.824358647991;6197.845053873689;6218.502519798496;6285.151504546828;6320.778772742932;6432.742690292336;6447.516436267205;6449.645269510222;6457.967710161419;6462.095306656977;6108.816735497942;6142.32434432221;6265.718042687999;6278.6546739929745;6282.717277722422;6098.9466267744565;6100.429789472632;6129.053167990599;6143.354947730602;6235.781747638811;6227.564070515021;6241.095041219089;6270.811875643738;6278.183836101278;6283.560150789401;6284.874302514016;6301.369863013845;6306.716197771019;6306.873862522632;6313.526485513697;5995.46040856325;6019.187409699634;6069.666168360606;6080.513558915188;6115.908261376172 09:16:02 INFO - PID 5322 | |1;ext.html;723.6888138100614;771.8842173673949;788.6805843633331;1109.5007246738965;1118.1055155875308;28633.713662863363;29237;34152.65847341526;37787.4331885022;37829.43255851157;38647;39005.609943900556;39041.21917561647;39160.21679566414;39205;7022.403095736856;7128.714851405937;7144.964275178617;7146.106736657918;7151.463640226978;5420.268263784378;5589.301337332833;6975.162980442328;7153.964230178868;7162.104736907887;7213.134423869117;7265.564066156021;7266.45501587383;7271.381932535714;7309.415246780245;7144.964275178617;7270.272972702687;7273.163586187603;7279.162896266891;7281.526700764433;5449.264349312832;5606.551475881961;6956.060931774196;7133.25100864407;7146.749863754743;36618;36784.63215367839;36934.81532592347;37006.81496592527;37021.259574808355;3483.146629075867;3500.194955160302;3530.6998905093096;3533.1873669056004;3535;27759.861200694068;28039.158825235274;28047.719522804713;28184.154475365773;28189.154325370066;3724.16206353571;3749.418840079792;3752.6810221131236;3762.529683789526;3772.6981841452616;184473;184906.07546962315;185250;185603;185723;45524;45803.31295030577;45832;45942;46101.30848037282;48196.27705584419;48217.517824822;48239.75880120647;48242.75878620655;48300.27549586759;15622;15649;15687.764683529756;15781.36874525029;15785.131817749905;10889.128869690407;12917.935410322889;12941.352932353344;12947.676308092185;12953.740925181632;12518.248905065726;14956.551303460914;15251.23743812805;15345.693086138215;15376.231188440534;5876.941230587639;5887.970560147171;5910.320313163955;6017.338092809788;6063.211782468251;1054.7837693273013;1088.7441451258949;1093.9671809845718;1109.1514991031868;1113.549012649878;26274.21177364682;26427;26600.06899758538;26748.866255668596;27019.86490067537;1152.2452793420323;1176.847009888709;1182.2079206931205;1183.946722397477;1184.0705046538426;13334.399952002183;13436.723511266415;13448.193108413527;13733.45066197361;13778.724425511635;261009;261482;261677;262102;262498;51106.74446627743;51711.48288517067;51723.74138129285;51771;51817;1455.3887367305756;1485.843986381436;1509.411329581464;1526.0690978503105;1541.368039103962;24145.275641730776;25100.040364979115;25114.90807354073;25436.618450722883;25594.232173034838;38193.80903095467;38362.23275534529;38392.61607384002;38398;38632.227355453295;225173;225178;225268;225766;226264;29723.85138074296;30230.697693022787;34236.657633424344;34429.82785086059;34550.48174277337;37734.62265377311;37977.442706874244;38465;39459;40158.59841401548;29654.851725742097;29756.702432975395;29782.10653680393;30065.699343007163;30652.540211897285;29116.56325155082;29292.267693307836;29633.25916852096;29977;30090.69909300879;33178;34405.82797085999;34508.30983380369;34840.30319393547;34848.47727284042;29195;29375.55936660909;29389.26526836846;29515.40969180647;30411.087667370015;22988.540229194987;24072.277831665077;24243.909024093606;24626.261212163663;24657 09:16:02 INFO - PID 5322 | |2;jquery.html;387.42363284160314;429.5597013061612;433.4080417971591;445.75928998340896;467.18243074619437;17110.82889171108;18819.717704234412;18946.810531894676;19857.40427787165;19874.90062549687;22161.778382216173;22239.552089582106;22273.55452890941;22369;22560.88719556404;4293.699441039136;5535.750891209885;5579.637323573955;5581.7209139543065;5587.944120558783;5331.440198779131;5413.83758487246;5424.240606315119;5443.074677304848;5501.037318469272;5261.526462618363;5388.4611538846;5390.757415916273;5391.487808658171;5391.946080539184;5278.683279003234;5392.325959255093;5396.757145928423;5400;5404.216388623648;4933.580645645125;4973.90052198954;4977.203647416432;4980.551750342486;4983.651144419892;24663.383415414577;24859.62710559343;24995.87502062478;25075.12237071712;25152.496950060897;2133.296012315932;2186.486175748714;2188.6060509108356;2188.6388745856916;2189.321310393783;15697.921510392374;15975.520734377988;16484.587885302844;16578.50264492067;16705.91647041769;2153.8599991000533;2172.4025892879426;2190.539986602815;2202.25123458025;2213.0373287619936;90625.54687226587;104439;115093.4245328768;115195.42402287935;115427;11495.942520287344;11807.704807379712;11939.343336116597;11963.581274655346;12086.39568021613;16647.41734039327;16663.833361666228;16680.83319166817;16693.248803803857;16774.49676509707;8660.30717542595;9034.32242581809;9061.54692265384;9269.72190834276;9289.674861379819;8487.618057187441;8690.826183476294;8700.216980471789;8864.911350886538;8881.333899957399;7438.814029649194;7583.582902940391;7583.848323033619;7592.582407967615;7666.8849967250535;2177.303262955839;2204.1427501871726;2221.8222542196586;2223.377454312795;2248.6289762189053;1032.210359075293;1065.9786804263872;1066.4720963123305;1081.113486940701;1116.7543140509074;20027.799722002885;20502.794972050386;20611.896940515202;20805.479863003548;20829.375118746462;1076.8040930453992;1084.3656460970421;1093.3603841752663;1097.3251450357973;1113.2986218682179;10679.14566834667;11035.282706624044;11156.888431115747;11166.776664466828;11172.44137793107;432552.7447255312;433790;436235;438945;453466;92529;94716.52641736885;95288;95786;97572;1185.6146752305362;1214.4049415786378;1227.2881728597392;1231.2243286729304;1246.6197809667956;16514;16865.494035178963;17005.65988680244;17186.140692965553;17667.204975775865;43381;43837.34243986279;43894.780526097165;44021.33967990419;44807;56342;61665.69167154135;63956.36043639504;64587;64633.67683161554;9245.167934885892;9319.767005824908;9324.860127098234;9596.376235544667;9939.65211217604;15103.169325687193;15306.464273750365;15408.075515469107;15509;15720.92139539295;12841.293728845003;12860.614181574567;13077.934610326887;13211.801822972855;13420.060595758594;12149.574764883188;12242.693932651755;12260.632181034583;12689.23864568129;12926.676833079247;12857.549985750455;13452.5291614793;14036.719265614835;14118.364673589507;14170.574882753534;9463.621455141718;9529.142377186086;9536.57085431171;9644.710658680251;9974.251931105051;12234.694132646755;12387.938060309642;13035.217886926815;13356.866431335951;13383 09:16:02 INFO - PID 5322 | |3;mootools.html;1378.3108445777111;1389.5761792653243;1413.3427956000464;1429.313929313929;1437.3460075665573;11024.889751102486;11430.37132957687;11451.770964580703;11464.082873370111;11488.368139752321;11201.271917325368;11227.494762735676;11756.41217939104;11833.349165795878;11850.229735067214;2380.809535237181;2416.867072311022;2433.367324495635;2435.427674496492;2440.8535487870786;2224.254874617005;2330.0563271874917;2331.219041621059;2335.94160145996;2337.6960995070704;2019.9293024744136;2101.3275751759443;2102.810747032767;2104.2635077722753;2272.4773302140516;2321.9999999999914;2344.0037983856964;2357.292812156363;2361.917332893349;2363.9409014774596;2356.1988923766003;2369.9881500592387;2374.5963186258377;2385.2009576791797;2420.189236605739;11508.769824603545;11611.477483513263;11626.360550169737;11633.47649355781;11635.418229088596;6520.836979075513;6732.730690772412;6733.192016957947;6736.663166841688;6740.93259067408;3960.584138665456;4194.6853985950875;4196.706230563862;4213.2837417639075;4225.72532785368;1211.5880600595751;1247.095855504761;1262.722201115753;1270.1680399338356;1295.4170623219588;16748.58128546784;16899;16926.49220523374;16932.915335423368;16949.49151525456;5173.896522069537;5334.386545547234;5524.475174858382;5607.046802043662;5661.150827375926;12483.375831208403;12490.87509124897;12537;12578.056645751605;12582.622521324374;6507.642079685568;6512.641804700787;6515.8696826063215;6515.934840651627;6542.509311801634;6054.455099041019;6090.23872015998;6300.842478938063;6329.430351268408;6345.619262844244;4280.400743895857;4344.956550434518;4348.152110338454;4389.6927215095275;4449.688521803443;1247.4885297028356;1259.6221133659865;1265.5127775806382;1274.2927675140386;1274.6877015131167;877.2148926710686;904.3172404834309;913.0869130869131;916.7295647783919;923.215267023025;6852.629474105199;7484.251574842472;8036.116027236999;8094.78578213261;8133.430659853753;984.6947446291673;991.7421470417744;998.605550807427;1000.7748256642343;1004.0561871840447;4457.576530229623;4762.238041913277;4881.219004959189;4938.654294199443;4962.131626965339;14886.288076871082;15049.473268435551;15650.921745391428;15700.76448853268;15873.841261587468;15299.235038248265;15447.382104715685;15518.612034699223;15599.142047187514;15601.297941592655;9217.44695318283;9421.24630029596;9434.198093162158;9443.244540436886;9457.574409151604;7190.6404679764755;7285.307895749896;7421.146568144516;7613.352865006315;7641.197674244125;13332.200067995951;13897.305134743021;13935.651608709863;13997.370118344903;14002.789958150432;13784.241866697026;14168.079074860101;14221.217833019284;14381.28093595337;14609.196494192922;9443.480608566595;9528.857067144138;9655.903440965782;9669.854952175583;9674.08096230857;10884.455777211266;11277.379744114152;11293.153013523854;11302.265352752045;11307.321560706385;9323.580438880132;9426.434413935185;9447.102525260088;9747.268954828558;9809.411435313905;9318.72043838686;9346.01866804003;9367.203787678123;9371.250299976122;9556.187724043533;8239.299659529008;8374.581270936307;8385.203405676451;8399.916000840158;8407.95796021016;6200.658963757037;6254.437100660963;6257.968710156602;6311.179546658905;6383.425491705769;6271.62370257786;6372.107904893226;6382.3617638237665;6392.360763923571;6550 09:16:02 INFO - PID 5322 | |4;prototype.html;368.0485943835186;404.67603654946885;409.9487564054493;415.1965945894692;417.0553695878833;5649.717514124298;5699.68651724155;5849.064149736043;5850.941490585098;5864.706764661772;5657.981563318601;5740;5742.971285143579;5765.1352297155445;5790.218320526738;4266.3813747006825;4568.977155114219;4571.268597024477;4579.267317229235;4589.518100599439;4381.671374646899;4398.164348773738;4402.735835849843;4407.559244075599;4419.779011049451;4342.804573794187;4348.586884246008;4351.238533256684;4360.36774667673;4377.474703035624;4298.892527686801;4354.346847972798;4356.411884395598;4359.433273674418;4360.324149756777;3763.5671897731836;3812.4662547243406;3816.923661526754;3823.1971286029966;3835.827387767529;5634.887302253973;5637.098064309691;5637.126245431965;5651.2935883014625;5665.575081868877;4999.625028122905;5120.76956536953;5128.820371314581;5135.666181698178;5143.9454911743005;6111.327753947062;6158;6159.846003849895;6184.690765461709;6189.133521307021;4284.121755040209;4297.926982207705;4303.91392172158;4303.988562687766;4304.096139810644;6282.947284345057;6391.169148010776;6401.519886008521;6441.130447389589;6485;4334.26317526015;4358.237308471068;4365.432493775789;4374.103308821684;4375.9278976650285;4408.096340250241;4421.093675796454;4432.711873728197;4442.822287108479;4469.664775141812;5020.146575082244;5111.182210846247;5141.845744627667;5152.175651895754;5155.664881782672;6210.130581718563;6217.937820621826;6225.066240064027;6231.4391704746795;6243.219597550306;1519.5061604978425;1524.3232444311168;1533.4709525213784;1533.9233038348038;1535.4242159190303;1011.0849681038671;1017.6794309792392;1024.990384471295;1026.5534492495788;1038.6676263595728;5874.265716785402;5895.616784908967;5896.64620122794;5896.911546326808;5899.498542623839;6250.218722659668;6340.524460665469;6384.553081284358;6392.20097487814;6415.486761059104;5916.615419997686;5945.40545945402;5954.970225148846;5959.880802384015;5970.850728731816;3320.3857286402094;3331.2005118771804;3341.732661387083;3353.966460335414;3372.40982828009;6535.078952628385;6573.967130164414;6575.967120164464;6585.275619681831;6593.439557637558;6462.4183823456115;6466.741330346827;6470.64411457365;6484;6489;5789.681567513827;6248.687565621701;6288.213973253343;6429.678516074271;6554.770583029571;6299.212598425197;6307.842303942438;6328.398802113792;6339.175907132043;6356.713947872449;6048.153258543895;6213.316535181217;6253.904941844351;6276.811695649138;6280.937190628035;6607.603543787388;6645.1361323027695;6649.567778094409;6653.101831252912;6669.566478178903;4358.128374325211;4364.75993820343;4377.430933978562;4387.846425375096;4400.361947517528;6239.532035097295;6325.209348831396;6356.745730170742;6380.585261957958;6407.199100112486;6167.5682702210415;6171.814845554641;6203.16257305258;6210.378962103753;6216.129741836056;6113.938860611337;6170.228721409824;6203.100550420074;6204.100405441276;6216.316205217332;6170.79669464468;6178.165947597017;6189.0097584386285;6199.349068347939;6202.782902598387;6014.428629280212;6167.475764560063;6207.472364848858;6223.813285601439;6224.470919971853;5979.760809567742;5994.760209591742;5998.640081595118;5998.640081595118;6064.939350606437 09:16:02 INFO - PID 5322 | |5;yui.html;859.9785005374861;884.0761404332472;886.1581497577299;889.6975028490313;897.7396554999051;15051;15641.452549160753;15713.135777532245;15795.210239488037;15865.365385384599;18586.256549738027;18747.718784218247;19164;19281.517962050955;19309.71035434466;1000.5047501486756;1025.2976710952985;1040.4953597505205;1040.568164211855;1041.0578426524007;1037.9325343852663;1039.9792004159913;1040.0223789637755;1040.1107053469289;1041.229490177267;1099.2744788439632;1099.4063205868822;1099.983500247497;1100.2958106811607;1103.713034611003;1093.3877028863863;1093.748437859289;1093.9562417503291;1095.2169199022658;1098.6154845803985;800.8118092248317;802.5906787538339;803.0604193094035;803.4175222963363;805.3879051920524;16641.583960401087;16852.915735421244;18552.165152568166;18749.156287967075;18791.812081879143;19165.425037248904;19337.12982915758;19442.319518816916;19731.40805775829;19817.50456238591;14345.71308573833;14462.638434039232;14621.853781462156;14664.926675366554;14669.92665036668;3315.1049216711353;3377.493375993596;3421.196018935548;3422.4524076147945;3424.6917777399904;86909;88860;89745.55127224387;90006;91686;14474.131552106906;14584.343704533321;14611.269436528131;14729.55811325662;14820.03669761462;27746.861265693948;27780.305492362448;27797.305067373476;27888.72111278861;28276.85861570679;7933.048034235929;7972.960135199287;8003.559804210824;8048.114707382183;8049.3962952778775;4052.3516237402464;4141.254574176617;4143.606357396043;4159.604837540429;4163.02168990287;4612.077584483117;4658.86023419298;4682.484926658065;4701.858944231679;4714.599259062949;864.9394542381973;887.3965703321803;895.7223260789176;899.860521619137;902.0843843498723;624.2072567838819;643.6073994863129;648.6043513456788;649.8895187817988;663.3002182697301;11147.052500537546;12028.879711202952;12741.426635801412;12913.789918777084;12996.740065198832;691.695653912277;697.0346070692128;704.3238491048651;706.388973537886;707.9433645308363;5368.3826359968325;5469.124940009579;5567.276254086943;5571.470710282517;5614.921602140102;85646;86932;87189;90926;91290;74257.62871185718;75508.62245688736;75800;76613.23386766172;79238;61424.69287653533;67936;69928;70110;70421.6478917602;12747.362631868184;13737.450501980209;13811.654708632364;13899.30503474802;14003.789943151065;32742;32792.34415311728;33462.66537334595;33536.48476364234;33606.49590256099;39341.2131757369;54784.9604815201;55851.720741396035;56936;57447.71276143593;15872.60318492047;15936.20318984023;16072.678546428771;16224.18879056066;16836.747448788523;18968.336108236144;19567.804321957166;19599;19705.507362316057;19783.802161978198;17404.303827846743;17990;18949.526261843563;19005;19023.619527609648;18352.17415216345;18557.536061598566;18674.533136671693;18715.344962926232;18942.147603357607;17290.91354543219;18037.819621803614;18286.35997740126;18380.90809545997;18649;11297.604583839526;11562.190646654653;11732.88267117352;11886.643400697993;12400.81398779001;14068.648283792578;14766.630834228801;14792.186429746036;14906.552803415914;14927.253637318308 09:16:02 INFO - PID 5322 | __end_tp_report 09:16:02 INFO - PID 5322 | __start_cc_report 09:16:02 INFO - PID 5322 | _x_x_mozilla_cycle_collect,1424 09:16:02 INFO - PID 5322 | __end_cc_report 09:16:02 INFO - PID 5322 | __startTimestamp1494000962845__endTimestamp 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | ------- Summary: start ------- 09:16:02 INFO - PID 5322 | Number of tests: 6 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | [#0] dojo.html Cycles:165 Average:5895.73 Median:6115.91 stddev:708.97 (11.6%) stddev-sans-first:683.25 09:16:02 INFO - PID 5322 | Values: 3385.7 3388.6 3405.9 3408.1 3451.2 5734.4 5867.8 5871.4 5915.7 5927.9 5875.2 5901.6 5921.3 5969.4 6002.5 4843.6 5116.0 5185.0 5207.4 5291.4 5251.2 5254.9 5323.4 5325.9 5327.0 5118.1 5168.9 5208.9 5263.1 5273.5 5209.0 5215.5 5231.7 5232.3 5243.7 5121.5 5139.3 5143.5 5155.5 5163.1 5809.9 5829.2 5833.0 5834.7 5870.0 6139.2 6246.0 6248.3 6389.1 6409.3 6484.5 6484.5 6485.3 6495.2 6503.2 5914.0 6021.5 6029.2 6033.1 6037.3 6403.4 6480.1 6509.8 6577.1 6599.9 6129.2 6219.9 6236.8 6333.4 6335.7 6402.6 6412.0 6416.8 6422.8 6426.8 6336.9 6340.0 6403.0 6410.1 6410.3 6181.3 6236.1 6260.3 6292.8 6322.4 4932.7 4933.4 4956.2 4962.6 4975.6 4362.7 4366.9 4376.1 4404.1 4410.7 5882.9 5937.5 6024.8 6028.5 6030.9 6338.9 6411.5 6455.6 6457.0 6473.3 6006.1 6039.2 6047.8 6048.1 6050.6 5452.4 5627.4 5629.1 5634.3 5678.5 6575.9 6597.1 6726.8 6734.5 6740.6 6462.0 6472.5 6478.3 6483.0 6483.3 6670.8 6711.0 6731.3 6733.7 6736.4 6133.8 6197.8 6218.5 6285.2 6320.8 6432.7 6447.5 6449.6 6458.0 6462.1 6108.8 6142.3 6265.7 6278.7 6282.7 6098.9 6100.4 6129.1 6143.4 6235.8 6227.6 6241.1 6270.8 6278.2 6283.6 6284.9 6301.4 6306.7 6306.9 6313.5 5995.5 6019.2 6069.7 6080.5 6115.9 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | [#1] ext.html Cycles:180 Average:38158.08 Median:31915.27 stddev:58870.08 (184.5%) stddev-sans-first:58968.13 09:16:02 INFO - PID 5322 | Values: 723.7 771.9 788.7 1109.5 1118.1 28633.7 29237.0 34152.7 37787.4 37829.4 38647.0 39005.6 39041.2 39160.2 39205.0 7022.4 7128.7 7145.0 7146.1 7151.5 5420.3 5589.3 6975.2 7154.0 7162.1 7213.1 7265.6 7266.5 7271.4 7309.4 7145.0 7270.3 7273.2 7279.2 7281.5 5449.3 5606.6 6956.1 7133.3 7146.7 36618.0 36784.6 36934.8 37006.8 37021.3 3483.1 3500.2 3530.7 3533.2 3535.0 27759.9 28039.2 28047.7 28184.2 28189.2 3724.2 3749.4 3752.7 3762.5 3772.7 184473.0 184906.1 185250.0 185603.0 185723.0 45524.0 45803.3 45832.0 45942.0 46101.3 48196.3 48217.5 48239.8 48242.8 48300.3 15622.0 15649.0 15687.8 15781.4 15785.1 10889.1 12917.9 12941.4 12947.7 12953.7 12518.2 14956.6 15251.2 15345.7 15376.2 5876.9 5888.0 5910.3 6017.3 6063.2 1054.8 1088.7 1094.0 1109.2 1113.5 26274.2 26427.0 26600.1 26748.9 27019.9 1152.2 1176.8 1182.2 1183.9 1184.1 13334.4 13436.7 13448.2 13733.5 13778.7 261009.0 261482.0 261677.0 262102.0 262498.0 51106.7 51711.5 51723.7 51771.0 51817.0 1455.4 1485.8 1509.4 1526.1 1541.4 24145.3 25100.0 25114.9 25436.6 25594.2 38193.8 38362.2 38392.6 38398.0 38632.2 225173.0 225178.0 225268.0 225766.0 226264.0 29723.9 30230.7 34236.7 34429.8 34550.5 37734.6 37977.4 38465.0 39459.0 40158.6 29654.9 29756.7 29782.1 30065.7 30652.5 29116.6 29292.3 29633.3 29977.0 30090.7 33178.0 34405.8 34508.3 34840.3 34848.5 29195.0 29375.6 29389.3 29515.4 30411.1 22988.5 24072.3 24243.9 24626.3 24657.0 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | [#2] jquery.html Cycles:180 Average:29364.10 Median:12187.41 stddev:73401.60 (602.3%) stddev-sans-first:73575.27 09:16:02 INFO - PID 5322 | Values: 387.4 429.6 433.4 445.8 467.2 17110.8 18819.7 18946.8 19857.4 19874.9 22161.8 22239.6 22273.6 22369.0 22560.9 4293.7 5535.8 5579.6 5581.7 5587.9 5331.4 5413.8 5424.2 5443.1 5501.0 5261.5 5388.5 5390.8 5391.5 5391.9 5278.7 5392.3 5396.8 5400.0 5404.2 4933.6 4973.9 4977.2 4980.6 4983.7 24663.4 24859.6 24995.9 25075.1 25152.5 2133.3 2186.5 2188.6 2188.6 2189.3 15697.9 15975.5 16484.6 16578.5 16705.9 2153.9 2172.4 2190.5 2202.3 2213.0 90625.5 104439.0 115093.4 115195.4 115427.0 11495.9 11807.7 11939.3 11963.6 12086.4 16647.4 16663.8 16680.8 16693.2 16774.5 8660.3 9034.3 9061.5 9269.7 9289.7 8487.6 8690.8 8700.2 8864.9 8881.3 7438.8 7583.6 7583.8 7592.6 7666.9 2177.3 2204.1 2221.8 2223.4 2248.6 1032.2 1066.0 1066.5 1081.1 1116.8 20027.8 20502.8 20611.9 20805.5 20829.4 1076.8 1084.4 1093.4 1097.3 1113.3 10679.1 11035.3 11156.9 11166.8 11172.4 432552.7 433790.0 436235.0 438945.0 453466.0 92529.0 94716.5 95288.0 95786.0 97572.0 1185.6 1214.4 1227.3 1231.2 1246.6 16514.0 16865.5 17005.7 17186.1 17667.2 43381.0 43837.3 43894.8 44021.3 44807.0 56342.0 61665.7 63956.4 64587.0 64633.7 9245.2 9319.8 9324.9 9596.4 9939.7 15103.2 15306.5 15408.1 15509.0 15720.9 12841.3 12860.6 13077.9 13211.8 13420.1 12149.6 12242.7 12260.6 12689.2 12926.7 12857.5 13452.5 14036.7 14118.4 14170.6 9463.6 9529.1 9536.6 9644.7 9974.3 12234.7 12387.9 13035.2 13356.9 13383.0 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | [#3] mootools.html Cycles:180 Average:7331.49 Median:2438.14 stddev:4661.63 (191.2%) stddev-sans-first:4653.24 09:16:02 INFO - PID 5322 | Values: 1378.3 1389.6 1413.3 1429.3 1437.3 11024.9 11430.4 11451.8 11464.1 11488.4 11201.3 11227.5 11756.4 11833.3 11850.2 2380.8 2416.9 2433.4 2435.4 2440.9 2224.3 2330.1 2331.2 2335.9 2337.7 2019.9 2101.3 2102.8 2104.3 2272.5 2322.0 2344.0 2357.3 2361.9 2363.9 2356.2 2370.0 2374.6 2385.2 2420.2 11508.8 11611.5 11626.4 11633.5 11635.4 6520.8 6732.7 6733.2 6736.7 6740.9 3960.6 4194.7 4196.7 4213.3 4225.7 1211.6 1247.1 1262.7 1270.2 1295.4 16748.6 16899.0 16926.5 16932.9 16949.5 5173.9 5334.4 5524.5 5607.0 5661.2 12483.4 12490.9 12537.0 12578.1 12582.6 6507.6 6512.6 6515.9 6515.9 6542.5 6054.5 6090.2 6300.8 6329.4 6345.6 4280.4 4345.0 4348.2 4389.7 4449.7 1247.5 1259.6 1265.5 1274.3 1274.7 877.2 904.3 913.1 916.7 923.2 6852.6 7484.3 8036.1 8094.8 8133.4 984.7 991.7 998.6 1000.8 1004.1 4457.6 4762.2 4881.2 4938.7 4962.1 14886.3 15049.5 15650.9 15700.8 15873.8 15299.2 15447.4 15518.6 15599.1 15601.3 9217.4 9421.2 9434.2 9443.2 9457.6 7190.6 7285.3 7421.1 7613.4 7641.2 13332.2 13897.3 13935.7 13997.4 14002.8 13784.2 14168.1 14221.2 14381.3 14609.2 9443.5 9528.9 9655.9 9669.9 9674.1 10884.5 11277.4 11293.2 11302.3 11307.3 9323.6 9426.4 9447.1 9747.3 9809.4 9318.7 9346.0 9367.2 9371.3 9556.2 8239.3 8374.6 8385.2 8399.9 8408.0 6200.7 6254.4 6258.0 6311.2 6383.4 6271.6 6372.1 6382.4 6392.4 6550.0 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | [#4] prototype.html Cycles:180 Average:5143.12 Median:5869.49 stddev:1551.03 (26.4%) stddev-sans-first:1513.40 09:16:02 INFO - PID 5322 | Values: 368.0 404.7 409.9 415.2 417.1 5649.7 5699.7 5849.1 5850.9 5864.7 5658.0 5740.0 5743.0 5765.1 5790.2 4266.4 4569.0 4571.3 4579.3 4589.5 4381.7 4398.2 4402.7 4407.6 4419.8 4342.8 4348.6 4351.2 4360.4 4377.5 4298.9 4354.3 4356.4 4359.4 4360.3 3763.6 3812.5 3816.9 3823.2 3835.8 5634.9 5637.1 5637.1 5651.3 5665.6 4999.6 5120.8 5128.8 5135.7 5143.9 6111.3 6158.0 6159.8 6184.7 6189.1 4284.1 4297.9 4303.9 4304.0 4304.1 6282.9 6391.2 6401.5 6441.1 6485.0 4334.3 4358.2 4365.4 4374.1 4375.9 4408.1 4421.1 4432.7 4442.8 4469.7 5020.1 5111.2 5141.8 5152.2 5155.7 6210.1 6217.9 6225.1 6231.4 6243.2 1519.5 1524.3 1533.5 1533.9 1535.4 1011.1 1017.7 1025.0 1026.6 1038.7 5874.3 5895.6 5896.6 5896.9 5899.5 6250.2 6340.5 6384.6 6392.2 6415.5 5916.6 5945.4 5955.0 5959.9 5970.9 3320.4 3331.2 3341.7 3354.0 3372.4 6535.1 6574.0 6576.0 6585.3 6593.4 6462.4 6466.7 6470.6 6484.0 6489.0 5789.7 6248.7 6288.2 6429.7 6554.8 6299.2 6307.8 6328.4 6339.2 6356.7 6048.2 6213.3 6253.9 6276.8 6280.9 6607.6 6645.1 6649.6 6653.1 6669.6 4358.1 4364.8 4377.4 4387.8 4400.4 6239.5 6325.2 6356.7 6380.6 6407.2 6167.6 6171.8 6203.2 6210.4 6216.1 6113.9 6170.2 6203.1 6204.1 6216.3 6170.8 6178.2 6189.0 6199.3 6202.8 6014.4 6167.5 6207.5 6223.8 6224.5 5979.8 5994.8 5998.6 5998.6 6064.9 09:16:02 INFO - PID 5322 | 09:16:02 INFO - PID 5322 | [#5] yui.html Cycles:180 Average:19852.05 Median:19652.25 stddev:24250.67 (123.4%) stddev-sans-first:24276.76 09:16:02 INFO - PID 5322 | Values: 860.0 884.1 886.2 889.7 897.7 15051.0 15641.5 15713.1 15795.2 15865.4 18586.3 18747.7 19164.0 19281.5 19309.7 1000.5 1025.3 1040.5 1040.6 1041.1 1037.9 1040.0 1040.0 1040.1 1041.2 1099.3 1099.4 1100.0 1100.3 1103.7 1093.4 1093.7 1094.0 1095.2 1098.6 800.8 802.6 803.1 803.4 805.4 16641.6 16852.9 18552.2 18749.2 18791.8 19165.4 19337.1 19442.3 19731.4 19817.5 14345.7 14462.6 14621.9 14664.9 14669.9 3315.1 3377.5 3421.2 3422.5 3424.7 86909.0 88860.0 89745.6 90006.0 91686.0 14474.1 14584.3 14611.3 14729.6 14820.0 27746.9 27780.3 27797.3 27888.7 28276.9 7933.0 7973.0 8003.6 8048.1 8049.4 4052.4 4141.3 4143.6 4159.6 4163.0 4612.1 4658.9 4682.5 4701.9 4714.6 864.9 887.4 895.7 899.9 902.1 624.2 643.6 648.6 649.9 663.3 11147.1 12028.9 12741.4 12913.8 12996.7 691.7 697.0 704.3 706.4 707.9 5368.4 5469.1 5567.3 5571.5 5614.9 85646.0 86932.0 87189.0 90926.0 91290.0 74257.6 75508.6 75800.0 76613.2 79238.0 61424.7 67936.0 69928.0 70110.0 70421.6 12747.4 13737.5 13811.7 13899.3 14003.8 32742.0 32792.3 33462.7 33536.5 33606.5 39341.2 54785.0 55851.7 56936.0 57447.7 15872.6 15936.2 16072.7 16224.2 16836.7 18968.3 19567.8 19599.0 19705.5 19783.8 17404.3 17990.0 18949.5 19005.0 19023.6 18352.2 18557.5 18674.5 18715.3 18942.1 17290.9 18037.8 18286.4 18380.9 18649.0 11297.6 11562.2 11732.9 11886.6 12400.8 14068.6 14766.6 14792.2 14906.6 14927.3 09:16:02 INFO - PID 5322 | -------- Summary: end -------- 09:16:02 INFO - PID 5322 | 09:16:03 INFO - TEST-INFO | 5322: exit 0 09:16:03 INFO - TEST-OK | dromaeo_css | took 1114769ms 09:16:03 INFO - TEST-START | kraken 09:16:03 INFO - operating with platform_type : linux_ 09:16:03 INFO - Initialising browser for kraken test... 09:16:03 INFO - TEST-INFO | started process 5654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPIQOZg/profile http://localhost:46459/getInfo.html) 09:16:07 INFO - PID 5654 | __metrics Screen width/height:1600/1200 09:16:07 INFO - PID 5654 | colorDepth:24 09:16:07 INFO - PID 5654 | Browser inner width/height: 1024/695 09:16:07 INFO - PID 5654 | __metrics 09:16:07 INFO - PID 5654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:07 INFO - PID 5654 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:09 INFO - TEST-INFO | 5654: exit 0 09:16:09 INFO - Browser initialized. 09:16:09 INFO - Running cycle 1/1 for kraken test... 09:16:09 INFO - TEST-INFO | started process 5751 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPIQOZg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:20 INFO - PID 5751 | RSS: Main: 149004288 09:16:20 INFO - PID 5751 | 09:16:23 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46459/tests/kraken/driver.html?testName=audio-beat-detection) 09:16:24 INFO - PID 5751 | RSS: Main: 222531584 09:16:24 INFO - PID 5751 | 09:16:26 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46459/tests/kraken/driver.html?testName=audio-dft) 09:16:27 INFO - PID 5751 | RSS: Main: 183332864 09:16:27 INFO - PID 5751 | 09:16:30 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46459/tests/kraken/driver.html?testName=audio-fft) 09:16:30 INFO - PID 5751 | RSS: Main: 263258112 09:16:30 INFO - PID 5751 | 09:16:32 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46459/tests/kraken/driver.html?testName=audio-oscillator) 09:16:33 INFO - PID 5751 | RSS: Main: 174682112 09:16:33 INFO - PID 5751 | 09:16:35 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46459/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:16:36 INFO - PID 5751 | RSS: Main: 177688576 09:16:36 INFO - PID 5751 | 09:16:39 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46459/tests/kraken/driver.html?testName=imaging-darkroom) 09:16:39 INFO - PID 5751 | RSS: Main: 238534656 09:16:39 INFO - PID 5751 | 09:16:43 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46459/tests/kraken/driver.html?testName=imaging-desaturate) 09:16:43 INFO - PID 5751 | RSS: Main: 257941504 09:16:43 INFO - PID 5751 | 09:16:46 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46459/tests/kraken/driver.html?testName=json-parse-financial) 09:16:46 INFO - PID 5751 | RSS: Main: 272162816 09:16:46 INFO - PID 5751 | 09:16:48 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46459/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:16:48 INFO - PID 5751 | RSS: Main: 202989568 09:16:48 INFO - PID 5751 | 09:16:50 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:16:51 INFO - PID 5751 | RSS: Main: 208269312 09:16:51 INFO - PID 5751 | 09:16:53 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:16:53 INFO - PID 5751 | RSS: Main: 218386432 09:16:53 INFO - PID 5751 | 09:16:56 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:16:57 INFO - PID 5751 | RSS: Main: 226246656 09:16:57 INFO - PID 5751 | 09:16:59 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:17:00 INFO - PID 5751 | RSS: Main: 221986816 09:17:00 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | Cycle 1(1): loaded http://localhost:46459/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46459/tests/kraken/driver.html?testName=ai-astar) 09:17:02 INFO - PID 5751 | RSS: Main: 272384000 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | __start_tp_report 09:17:02 INFO - PID 5751 | _x_x_mozilla_page_load 09:17:02 INFO - PID 5751 | _x_x_mozilla_page_load_details 09:17:02 INFO - PID 5751 | |i|pagename|runs| 09:17:02 INFO - PID 5751 | |0;ai-astar;110;112;110;109;111;111;108;109;109;108 09:17:02 INFO - PID 5751 | |1;audio-beat-detection;141;117;117;183;118;117;116;116;135;115 09:17:02 INFO - PID 5751 | |2;audio-dft;161;161;161;160;160;161;161;162;163;163 09:17:02 INFO - PID 5751 | |3;audio-fft;77;73;73;106;73;74;74;73;93;74 09:17:02 INFO - PID 5751 | |4;audio-oscillator;81;81;105;82;81;81;93;81;81;107 09:17:02 INFO - PID 5751 | |5;imaging-gaussian-blur;112;113;113;113;113;113;113;113;113;114 09:17:02 INFO - PID 5751 | |6;imaging-darkroom;146;146;147;147;147;146;147;147;147;147 09:17:02 INFO - PID 5751 | |7;imaging-desaturate;93;94;94;94;94;94;94;93;94;93 09:17:02 INFO - PID 5751 | |8;json-parse-financial;47;44;43;45;45;45;46;45;46;46 09:17:02 INFO - PID 5751 | |9;json-stringify-tinderbox;55;56;55;55;55;57;57;56;55;54 09:17:02 INFO - PID 5751 | |10;stanford-crypto-aes;84;80;81;81;81;80;82;81;80;81 09:17:02 INFO - PID 5751 | |11;stanford-crypto-ccm;128;127;135;139;126;125;124;123;168;131 09:17:02 INFO - PID 5751 | |12;stanford-crypto-pbkdf2;180;173;180;178;173;181;178;178;177;178 09:17:02 INFO - PID 5751 | |13;stanford-crypto-sha256-iterative;63;65;66;65;63;62;63;65;63;64 09:17:02 INFO - PID 5751 | __end_tp_report 09:17:02 INFO - PID 5751 | __start_cc_report 09:17:02 INFO - PID 5751 | _x_x_mozilla_cycle_collect,1618 09:17:02 INFO - PID 5751 | __end_cc_report 09:17:02 INFO - PID 5751 | __startTimestamp1494001022437__endTimestamp 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | ------- Summary: start ------- 09:17:02 INFO - PID 5751 | Number of tests: 14 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.34 (1.2%) stddev-sans-first:1.41 09:17:02 INFO - PID 5751 | Values: 110.0 112.0 110.0 109.0 111.0 111.0 108.0 109.0 109.0 108.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:117.50 stddev:21.50 (18.3%) stddev-sans-first:22.24 09:17:02 INFO - PID 5751 | Values: 141.0 117.0 117.0 183.0 118.0 117.0 116.0 116.0 135.0 115.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 09:17:02 INFO - PID 5751 | Values: 161.0 161.0 161.0 160.0 160.0 161.0 161.0 162.0 163.0 163.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#3] audio-fft Cycles:10 Average:79.00 Median:74.00 stddev:11.29 (15.3%) stddev-sans-first:11.96 09:17:02 INFO - PID 5751 | Values: 77.0 73.0 73.0 106.0 73.0 74.0 74.0 73.0 93.0 74.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:81.00 stddev:10.54 (13.0%) stddev-sans-first:10.93 09:17:02 INFO - PID 5751 | Values: 81.0 81.0 105.0 82.0 81.0 81.0 93.0 81.0 81.0 107.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 09:17:02 INFO - PID 5751 | Values: 112.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 09:17:02 INFO - PID 5751 | Values: 146.0 146.0 147.0 147.0 147.0 146.0 147.0 147.0 147.0 147.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 09:17:02 INFO - PID 5751 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 93.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#8] json-parse-financial Cycles:10 Average:45.20 Median:45.50 stddev:1.14 (2.5%) stddev-sans-first:1.00 09:17:02 INFO - PID 5751 | Values: 47.0 44.0 43.0 45.0 45.0 45.0 46.0 45.0 46.0 46.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:55.50 stddev:0.97 (1.8%) stddev-sans-first:1.01 09:17:02 INFO - PID 5751 | Values: 55.0 56.0 55.0 55.0 55.0 57.0 57.0 56.0 55.0 54.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:1.20 (1.5%) stddev-sans-first:0.67 09:17:02 INFO - PID 5751 | Values: 84.0 80.0 81.0 81.0 81.0 80.0 82.0 81.0 80.0 81.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:129.50 stddev:13.43 (10.4%) stddev-sans-first:14.14 09:17:02 INFO - PID 5751 | Values: 128.0 127.0 135.0 139.0 126.0 125.0 124.0 123.0 168.0 131.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:178.00 stddev:2.72 (1.5%) stddev-sans-first:2.74 09:17:02 INFO - PID 5751 | Values: 180.0 173.0 180.0 178.0 173.0 181.0 178.0 178.0 177.0 178.0 09:17:02 INFO - PID 5751 | 09:17:02 INFO - PID 5751 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.50 stddev:1.29 (2.0%) stddev-sans-first:1.32 09:17:02 INFO - PID 5751 | Values: 63.0 65.0 66.0 65.0 63.0 62.0 63.0 65.0 63.0 64.0 09:17:02 INFO - PID 5751 | -------- Summary: end -------- 09:17:02 INFO - PID 5751 | 09:17:03 INFO - TEST-INFO | 5751: exit 0 09:17:03 INFO - TEST-OK | kraken | took 60176ms 09:17:03 INFO - SUITE-END | took 1174s 09:17:04 INFO - Completed test suite (00:19:35) 09:17:04 INFO - javascript benchmark 09:17:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7726.350716799099, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3385.6952874241315, 3388.559487266655, 3405.9489107663376, 3408.130926613714, 3451.1889705919107, 5734.4265573442635, 5867.76528938843, 5871.442212989765, 5915.733791979355, 5927.911081333778, 5875.206847075643, 5901.645901245928, 5921.319048309435, 5969.432903874136, 6002.459778619924, 4843.58829499493, 5115.9744201278945, 5185.014847179043, 5207.401148867882, 5291.391489978644, 5251.186066159732, 5254.8686282842855, 5323.361196656388, 5325.893482130355, 5327.0, 5118.078745825768, 5168.948310516885, 5208.947910520922, 5263.131583288753, 5273.525382715536, 5209.0363282792805, 5215.452377500384, 5231.659942103789, 5232.2674825524455, 5243.711595862226, 5121.487851214849, 5139.331886854722, 5143.5113664201845, 5155.484451554852, 5163.122269214243, 5809.912851307234, 5829.242198514166, 5832.970835145839, 5834.708264586796, 5870.0, 6139.1712118863825, 6246.000639897595, 6248.250209974786, 6389.105525226472, 6409.262934762515, 6484.513661475408, 6484.546081774278, 6485.286618471964, 6495.220573531159, 6503.219613646345, 5913.970430147821, 6021.488173505258, 6029.216201893726, 6033.125196846433, 6037.335893051761, 6403.423691867754, 6480.125183100316, 6509.837254068686, 6577.079208910756, 6599.901001484982, 6129.186698318328, 6219.906701399483, 6236.750529978841, 6333.36666333363, 6335.65153916539, 6402.647854368054, 6411.96794016027, 6416.7754128605275, 6422.775202867971, 6426.775062872777, 6336.873262534724, 6340.048992651047, 6403.039544068334, 6410.134631824737, 6410.326915673881, 6181.289151747516, 6236.064590311399, 6260.280067792171, 6292.811215663537, 6322.430981211713, 4932.7286999215385, 4933.3586633738105, 4956.182229932021, 4962.5533701967, 4975.601951843843, 4362.672799539983, 4366.934495982563, 4376.059147283349, 4404.053128577371, 4410.735355878657, 5882.852928676732, 5937.495312898452, 6024.819255422344, 6028.457438830526, 6030.879382412327, 6338.904916426257, 6411.487081033506, 6455.644939528277, 6457.031445283245, 6473.28793832678, 6006.099085137177, 6039.214902062704, 6047.788327408608, 6048.092786082123, 6050.606710563799, 5452.372977107683, 5627.352854421794, 5629.127485239795, 5634.295713035871, 5678.545716342683, 6575.868482630416, 6597.1423714916755, 6726.831829204309, 6734.528582999339, 6740.62926539045, 6461.967690161707, 6472.482201423969, 6478.28738838718, 6483.027545868157, 6483.286838447862, 6670.7665231716655, 6710.966445167743, 6731.293214212633, 6733.66331683404, 6736.427403670743, 6133.824358647991, 6197.845053873689, 6218.502519798496, 6285.151504546828, 6320.778772742932, 6432.742690292336, 6447.516436267205, 6449.645269510222, 6457.967710161419, 6462.095306656977, 6108.816735497942, 6142.32434432221, 6265.718042687999, 6278.6546739929745, 6282.717277722422, 6098.9466267744565, 6100.429789472632, 6129.053167990599, 6143.354947730602, 6235.781747638811, 6227.564070515021, 6241.095041219089, 6270.811875643738, 6278.183836101278, 6283.560150789401, 6284.874302514016, 6301.369863013845, 6306.716197771019, 6306.873862522632, 6313.526485513697, 5995.46040856325, 6019.187409699634, 6069.666168360606, 6080.513558915188, 6115.908261376172], "value": 5845.822079025626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.6888138100614, 771.8842173673949, 788.6805843633331, 1109.5007246738965, 1118.1055155875308, 28633.713662863363, 29237.0, 34152.65847341526, 37787.4331885022, 37829.43255851157, 38647.0, 39005.609943900556, 39041.21917561647, 39160.21679566414, 39205.0, 7022.403095736856, 7128.714851405937, 7144.964275178617, 7146.106736657918, 7151.463640226978, 5420.268263784378, 5589.301337332833, 6975.162980442328, 7153.964230178868, 7162.104736907887, 7213.134423869117, 7265.564066156021, 7266.45501587383, 7271.381932535714, 7309.415246780245, 7144.964275178617, 7270.272972702687, 7273.163586187603, 7279.162896266891, 7281.526700764433, 5449.264349312832, 5606.551475881961, 6956.060931774196, 7133.25100864407, 7146.749863754743, 36618.0, 36784.63215367839, 36934.81532592347, 37006.81496592527, 37021.259574808355, 3483.146629075867, 3500.194955160302, 3530.6998905093096, 3533.1873669056004, 3535.0, 27759.861200694068, 28039.158825235274, 28047.719522804713, 28184.154475365773, 28189.154325370066, 3724.16206353571, 3749.418840079792, 3752.6810221131236, 3762.529683789526, 3772.6981841452616, 184473.0, 184906.07546962315, 185250.0, 185603.0, 185723.0, 45524.0, 45803.31295030577, 45832.0, 45942.0, 46101.30848037282, 48196.27705584419, 48217.517824822, 48239.75880120647, 48242.75878620655, 48300.27549586759, 15622.0, 15649.0, 15687.764683529756, 15781.36874525029, 15785.131817749905, 10889.128869690407, 12917.935410322889, 12941.352932353344, 12947.676308092185, 12953.740925181632, 12518.248905065726, 14956.551303460914, 15251.23743812805, 15345.693086138215, 15376.231188440534, 5876.941230587639, 5887.970560147171, 5910.320313163955, 6017.338092809788, 6063.211782468251, 1054.7837693273013, 1088.7441451258949, 1093.9671809845718, 1109.1514991031868, 1113.549012649878, 26274.21177364682, 26427.0, 26600.06899758538, 26748.866255668596, 27019.86490067537, 1152.2452793420323, 1176.847009888709, 1182.2079206931205, 1183.946722397477, 1184.0705046538426, 13334.399952002183, 13436.723511266415, 13448.193108413527, 13733.45066197361, 13778.724425511635, 261009.0, 261482.0, 261677.0, 262102.0, 262498.0, 51106.74446627743, 51711.48288517067, 51723.74138129285, 51771.0, 51817.0, 1455.3887367305756, 1485.843986381436, 1509.411329581464, 1526.0690978503105, 1541.368039103962, 24145.275641730776, 25100.040364979115, 25114.90807354073, 25436.618450722883, 25594.232173034838, 38193.80903095467, 38362.23275534529, 38392.61607384002, 38398.0, 38632.227355453295, 225173.0, 225178.0, 225268.0, 225766.0, 226264.0, 29723.85138074296, 30230.697693022787, 34236.657633424344, 34429.82785086059, 34550.48174277337, 37734.62265377311, 37977.442706874244, 38465.0, 39459.0, 40158.59841401548, 29654.851725742097, 29756.702432975395, 29782.10653680393, 30065.699343007163, 30652.540211897285, 29116.56325155082, 29292.267693307836, 29633.25916852096, 29977.0, 30090.69909300879, 33178.0, 34405.82797085999, 34508.30983380369, 34840.30319393547, 34848.47727284042, 29195.0, 29375.55936660909, 29389.26526836846, 29515.40969180647, 30411.087667370015, 22988.540229194987, 24072.277831665077, 24243.909024093606, 24626.261212163663, 24657.0], "value": 16681.821545007388, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.42363284160314, 429.5597013061612, 433.4080417971591, 445.75928998340896, 467.18243074619437, 17110.82889171108, 18819.717704234412, 18946.810531894676, 19857.40427787165, 19874.90062549687, 22161.778382216173, 22239.552089582106, 22273.55452890941, 22369.0, 22560.88719556404, 4293.699441039136, 5535.750891209885, 5579.637323573955, 5581.7209139543065, 5587.944120558783, 5331.440198779131, 5413.83758487246, 5424.240606315119, 5443.074677304848, 5501.037318469272, 5261.526462618363, 5388.4611538846, 5390.757415916273, 5391.487808658171, 5391.946080539184, 5278.683279003234, 5392.325959255093, 5396.757145928423, 5400.0, 5404.216388623648, 4933.580645645125, 4973.90052198954, 4977.203647416432, 4980.551750342486, 4983.651144419892, 24663.383415414577, 24859.62710559343, 24995.87502062478, 25075.12237071712, 25152.496950060897, 2133.296012315932, 2186.486175748714, 2188.6060509108356, 2188.6388745856916, 2189.321310393783, 15697.921510392374, 15975.520734377988, 16484.587885302844, 16578.50264492067, 16705.91647041769, 2153.8599991000533, 2172.4025892879426, 2190.539986602815, 2202.25123458025, 2213.0373287619936, 90625.54687226587, 104439.0, 115093.4245328768, 115195.42402287935, 115427.0, 11495.942520287344, 11807.704807379712, 11939.343336116597, 11963.581274655346, 12086.39568021613, 16647.41734039327, 16663.833361666228, 16680.83319166817, 16693.248803803857, 16774.49676509707, 8660.30717542595, 9034.32242581809, 9061.54692265384, 9269.72190834276, 9289.674861379819, 8487.618057187441, 8690.826183476294, 8700.216980471789, 8864.911350886538, 8881.333899957399, 7438.814029649194, 7583.582902940391, 7583.848323033619, 7592.582407967615, 7666.8849967250535, 2177.303262955839, 2204.1427501871726, 2221.8222542196586, 2223.377454312795, 2248.6289762189053, 1032.210359075293, 1065.9786804263872, 1066.4720963123305, 1081.113486940701, 1116.7543140509074, 20027.799722002885, 20502.794972050386, 20611.896940515202, 20805.479863003548, 20829.375118746462, 1076.8040930453992, 1084.3656460970421, 1093.3603841752663, 1097.3251450357973, 1113.2986218682179, 10679.14566834667, 11035.282706624044, 11156.888431115747, 11166.776664466828, 11172.44137793107, 432552.7447255312, 433790.0, 436235.0, 438945.0, 453466.0, 92529.0, 94716.52641736885, 95288.0, 95786.0, 97572.0, 1185.6146752305362, 1214.4049415786378, 1227.2881728597392, 1231.2243286729304, 1246.6197809667956, 16514.0, 16865.494035178963, 17005.65988680244, 17186.140692965553, 17667.204975775865, 43381.0, 43837.34243986279, 43894.780526097165, 44021.33967990419, 44807.0, 56342.0, 61665.69167154135, 63956.36043639504, 64587.0, 64633.67683161554, 9245.167934885892, 9319.767005824908, 9324.860127098234, 9596.376235544667, 9939.65211217604, 15103.169325687193, 15306.464273750365, 15408.075515469107, 15509.0, 15720.92139539295, 12841.293728845003, 12860.614181574567, 13077.934610326887, 13211.801822972855, 13420.060595758594, 12149.574764883188, 12242.693932651755, 12260.632181034583, 12689.23864568129, 12926.676833079247, 12857.549985750455, 13452.5291614793, 14036.719265614835, 14118.364673589507, 14170.574882753534, 9463.621455141718, 9529.142377186086, 9536.57085431171, 9644.710658680251, 9974.251931105051, 12234.694132646755, 12387.938060309642, 13035.217886926815, 13356.866431335951, 13383.0], "value": 10019.008360698, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.3108445777111, 1389.5761792653243, 1413.3427956000464, 1429.313929313929, 1437.3460075665573, 11024.889751102486, 11430.37132957687, 11451.770964580703, 11464.082873370111, 11488.368139752321, 11201.271917325368, 11227.494762735676, 11756.41217939104, 11833.349165795878, 11850.229735067214, 2380.809535237181, 2416.867072311022, 2433.367324495635, 2435.427674496492, 2440.8535487870786, 2224.254874617005, 2330.0563271874917, 2331.219041621059, 2335.94160145996, 2337.6960995070704, 2019.9293024744136, 2101.3275751759443, 2102.810747032767, 2104.2635077722753, 2272.4773302140516, 2321.9999999999914, 2344.0037983856964, 2357.292812156363, 2361.917332893349, 2363.9409014774596, 2356.1988923766003, 2369.9881500592387, 2374.5963186258377, 2385.2009576791797, 2420.189236605739, 11508.769824603545, 11611.477483513263, 11626.360550169737, 11633.47649355781, 11635.418229088596, 6520.836979075513, 6732.730690772412, 6733.192016957947, 6736.663166841688, 6740.93259067408, 3960.584138665456, 4194.6853985950875, 4196.706230563862, 4213.2837417639075, 4225.72532785368, 1211.5880600595751, 1247.095855504761, 1262.722201115753, 1270.1680399338356, 1295.4170623219588, 16748.58128546784, 16899.0, 16926.49220523374, 16932.915335423368, 16949.49151525456, 5173.896522069537, 5334.386545547234, 5524.475174858382, 5607.046802043662, 5661.150827375926, 12483.375831208403, 12490.87509124897, 12537.0, 12578.056645751605, 12582.622521324374, 6507.642079685568, 6512.641804700787, 6515.8696826063215, 6515.934840651627, 6542.509311801634, 6054.455099041019, 6090.23872015998, 6300.842478938063, 6329.430351268408, 6345.619262844244, 4280.400743895857, 4344.956550434518, 4348.152110338454, 4389.6927215095275, 4449.688521803443, 1247.4885297028356, 1259.6221133659865, 1265.5127775806382, 1274.2927675140386, 1274.6877015131167, 877.2148926710686, 904.3172404834309, 913.0869130869131, 916.7295647783919, 923.215267023025, 6852.629474105199, 7484.251574842472, 8036.116027236999, 8094.78578213261, 8133.430659853753, 984.6947446291673, 991.7421470417744, 998.605550807427, 1000.7748256642343, 1004.0561871840447, 4457.576530229623, 4762.238041913277, 4881.219004959189, 4938.654294199443, 4962.131626965339, 14886.288076871082, 15049.473268435551, 15650.921745391428, 15700.76448853268, 15873.841261587468, 15299.235038248265, 15447.382104715685, 15518.612034699223, 15599.142047187514, 15601.297941592655, 9217.44695318283, 9421.24630029596, 9434.198093162158, 9443.244540436886, 9457.574409151604, 7190.6404679764755, 7285.307895749896, 7421.146568144516, 7613.352865006315, 7641.197674244125, 13332.200067995951, 13897.305134743021, 13935.651608709863, 13997.370118344903, 14002.789958150432, 13784.241866697026, 14168.079074860101, 14221.217833019284, 14381.28093595337, 14609.196494192922, 9443.480608566595, 9528.857067144138, 9655.903440965782, 9669.854952175583, 9674.08096230857, 10884.455777211266, 11277.379744114152, 11293.153013523854, 11302.265352752045, 11307.321560706385, 9323.580438880132, 9426.434413935185, 9447.102525260088, 9747.268954828558, 9809.411435313905, 9318.72043838686, 9346.01866804003, 9367.203787678123, 9371.250299976122, 9556.187724043533, 8239.299659529008, 8374.581270936307, 8385.203405676451, 8399.916000840158, 8407.95796021016, 6200.658963757037, 6254.437100660963, 6257.968710156602, 6311.179546658905, 6383.425491705769, 6271.62370257786, 6372.107904893226, 6382.3617638237665, 6392.360763923571, 6550.0], "value": 5504.360332686464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.0485943835186, 404.67603654946885, 409.9487564054493, 415.1965945894692, 417.0553695878833, 5649.717514124298, 5699.68651724155, 5849.064149736043, 5850.941490585098, 5864.706764661772, 5657.981563318601, 5740.0, 5742.971285143579, 5765.1352297155445, 5790.218320526738, 4266.3813747006825, 4568.977155114219, 4571.268597024477, 4579.267317229235, 4589.518100599439, 4381.671374646899, 4398.164348773738, 4402.735835849843, 4407.559244075599, 4419.779011049451, 4342.804573794187, 4348.586884246008, 4351.238533256684, 4360.36774667673, 4377.474703035624, 4298.892527686801, 4354.346847972798, 4356.411884395598, 4359.433273674418, 4360.324149756777, 3763.5671897731836, 3812.4662547243406, 3816.923661526754, 3823.1971286029966, 3835.827387767529, 5634.887302253973, 5637.098064309691, 5637.126245431965, 5651.2935883014625, 5665.575081868877, 4999.625028122905, 5120.76956536953, 5128.820371314581, 5135.666181698178, 5143.9454911743005, 6111.327753947062, 6158.0, 6159.846003849895, 6184.690765461709, 6189.133521307021, 4284.121755040209, 4297.926982207705, 4303.91392172158, 4303.988562687766, 4304.096139810644, 6282.947284345057, 6391.169148010776, 6401.519886008521, 6441.130447389589, 6485.0, 4334.26317526015, 4358.237308471068, 4365.432493775789, 4374.103308821684, 4375.9278976650285, 4408.096340250241, 4421.093675796454, 4432.711873728197, 4442.822287108479, 4469.664775141812, 5020.146575082244, 5111.182210846247, 5141.845744627667, 5152.175651895754, 5155.664881782672, 6210.130581718563, 6217.937820621826, 6225.066240064027, 6231.4391704746795, 6243.219597550306, 1519.5061604978425, 1524.3232444311168, 1533.4709525213784, 1533.9233038348038, 1535.4242159190303, 1011.0849681038671, 1017.6794309792392, 1024.990384471295, 1026.5534492495788, 1038.6676263595728, 5874.265716785402, 5895.616784908967, 5896.64620122794, 5896.911546326808, 5899.498542623839, 6250.218722659668, 6340.524460665469, 6384.553081284358, 6392.20097487814, 6415.486761059104, 5916.615419997686, 5945.40545945402, 5954.970225148846, 5959.880802384015, 5970.850728731816, 3320.3857286402094, 3331.2005118771804, 3341.732661387083, 3353.966460335414, 3372.40982828009, 6535.078952628385, 6573.967130164414, 6575.967120164464, 6585.275619681831, 6593.439557637558, 6462.4183823456115, 6466.741330346827, 6470.64411457365, 6484.0, 6489.0, 5789.681567513827, 6248.687565621701, 6288.213973253343, 6429.678516074271, 6554.770583029571, 6299.212598425197, 6307.842303942438, 6328.398802113792, 6339.175907132043, 6356.713947872449, 6048.153258543895, 6213.316535181217, 6253.904941844351, 6276.811695649138, 6280.937190628035, 6607.603543787388, 6645.1361323027695, 6649.567778094409, 6653.101831252912, 6669.566478178903, 4358.128374325211, 4364.75993820343, 4377.430933978562, 4387.846425375096, 4400.361947517528, 6239.532035097295, 6325.209348831396, 6356.745730170742, 6380.585261957958, 6407.199100112486, 6167.5682702210415, 6171.814845554641, 6203.16257305258, 6210.378962103753, 6216.129741836056, 6113.938860611337, 6170.228721409824, 6203.100550420074, 6204.100405441276, 6216.316205217332, 6170.79669464468, 6178.165947597017, 6189.0097584386285, 6199.349068347939, 6202.782902598387, 6014.428629280212, 6167.475764560063, 6207.472364848858, 6223.813285601439, 6224.470919971853, 5979.760809567742, 5994.760209591742, 5998.640081595118, 5998.640081595118, 6064.939350606437], "value": 4659.143043227928, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [859.9785005374861, 884.0761404332472, 886.1581497577299, 889.6975028490313, 897.7396554999051, 15051.0, 15641.452549160753, 15713.135777532245, 15795.210239488037, 15865.365385384599, 18586.256549738027, 18747.718784218247, 19164.0, 19281.517962050955, 19309.71035434466, 1000.5047501486756, 1025.2976710952985, 1040.4953597505205, 1040.568164211855, 1041.0578426524007, 1037.9325343852663, 1039.9792004159913, 1040.0223789637755, 1040.1107053469289, 1041.229490177267, 1099.2744788439632, 1099.4063205868822, 1099.983500247497, 1100.2958106811607, 1103.713034611003, 1093.3877028863863, 1093.748437859289, 1093.9562417503291, 1095.2169199022658, 1098.6154845803985, 800.8118092248317, 802.5906787538339, 803.0604193094035, 803.4175222963363, 805.3879051920524, 16641.583960401087, 16852.915735421244, 18552.165152568166, 18749.156287967075, 18791.812081879143, 19165.425037248904, 19337.12982915758, 19442.319518816916, 19731.40805775829, 19817.50456238591, 14345.71308573833, 14462.638434039232, 14621.853781462156, 14664.926675366554, 14669.92665036668, 3315.1049216711353, 3377.493375993596, 3421.196018935548, 3422.4524076147945, 3424.6917777399904, 86909.0, 88860.0, 89745.55127224387, 90006.0, 91686.0, 14474.131552106906, 14584.343704533321, 14611.269436528131, 14729.55811325662, 14820.03669761462, 27746.861265693948, 27780.305492362448, 27797.305067373476, 27888.72111278861, 28276.85861570679, 7933.048034235929, 7972.960135199287, 8003.559804210824, 8048.114707382183, 8049.3962952778775, 4052.3516237402464, 4141.254574176617, 4143.606357396043, 4159.604837540429, 4163.02168990287, 4612.077584483117, 4658.86023419298, 4682.484926658065, 4701.858944231679, 4714.599259062949, 864.9394542381973, 887.3965703321803, 895.7223260789176, 899.860521619137, 902.0843843498723, 624.2072567838819, 643.6073994863129, 648.6043513456788, 649.8895187817988, 663.3002182697301, 11147.052500537546, 12028.879711202952, 12741.426635801412, 12913.789918777084, 12996.740065198832, 691.695653912277, 697.0346070692128, 704.3238491048651, 706.388973537886, 707.9433645308363, 5368.3826359968325, 5469.124940009579, 5567.276254086943, 5571.470710282517, 5614.921602140102, 85646.0, 86932.0, 87189.0, 90926.0, 91290.0, 74257.62871185718, 75508.62245688736, 75800.0, 76613.23386766172, 79238.0, 61424.69287653533, 67936.0, 69928.0, 70110.0, 70421.6478917602, 12747.362631868184, 13737.450501980209, 13811.654708632364, 13899.30503474802, 14003.789943151065, 32742.0, 32792.34415311728, 33462.66537334595, 33536.48476364234, 33606.49590256099, 39341.2131757369, 54784.9604815201, 55851.720741396035, 56936.0, 57447.71276143593, 15872.60318492047, 15936.20318984023, 16072.678546428771, 16224.18879056066, 16836.747448788523, 18968.336108236144, 19567.804321957166, 19599.0, 19705.507362316057, 19783.802161978198, 17404.303827846743, 17990.0, 18949.526261843563, 19005.0, 19023.619527609648, 18352.17415216345, 18557.536061598566, 18674.533136671693, 18715.344962926232, 18942.147603357607, 17290.91354543219, 18037.819621803614, 18286.35997740126, 18380.90809545997, 18649.0, 11297.604583839526, 11562.190646654653, 11732.88267117352, 11886.643400697993, 12400.81398779001, 14068.648283792578, 14766.630834228801, 14792.186429746036, 14906.552803415914, 14927.253637318308], "value": 8489.65665520174, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1474.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 110.0, 109.0, 111.0, 111.0, 108.0, 109.0, 109.0, 108.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 117.0, 183.0, 118.0, 117.0, 116.0, 116.0, 135.0, 115.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 162.0, 163.0, 163.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 73.0, 106.0, 73.0, 74.0, 74.0, 73.0, 93.0, 74.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 105.0, 82.0, 81.0, 81.0, 93.0, 81.0, 81.0, 107.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 44.0, 43.0, 45.0, 45.0, 45.0, 46.0, 45.0, 46.0, 46.0], "value": 45.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 55.0, 55.0, 57.0, 57.0, 56.0, 55.0, 54.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 81.0, 81.0, 81.0, 80.0, 82.0, 81.0, 80.0, 81.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 127.0, 135.0, 139.0, 126.0, 125.0, 124.0, 123.0, 168.0, 131.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 173.0, 180.0, 178.0, 173.0, 181.0, 178.0, 178.0, 177.0, 178.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 66.0, 65.0, 63.0, 62.0, 63.0, 65.0, 63.0, 64.0], "value": 63.9, "unit": "score"}]}]} 09:17:04 INFO - Return code: 0 09:17:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:17:04 INFO - # TBPL SUCCESS # 09:17:04 INFO - Running post-action listener: _resource_record_post_action 09:17:04 INFO - [mozharness: 2017-05-05 16:17:04.263026Z] Finished run-tests step (success) 09:17:04 INFO - Running post-run listener: _resource_record_post_run 09:17:04 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 359243776; Read time: 0; Write time: 331972 09:17:04 INFO - TinderboxPrint: CPU usage
12.9% 09:17:04 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:17:04 INFO - TinderboxPrint: I/O write bytes / time
359,243,776 / 331,972 09:17:04 INFO - TinderboxPrint: CPU idle
8,290.0 (87.1%) 09:17:04 INFO - TinderboxPrint: CPU user
1,180.1 (12.4%) 09:17:04 INFO - TinderboxPrint: Swap in / out
0 / 0 09:17:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 100569088; Read time: 0; Write time: 115412 09:17:04 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 258097152; Read time: 0; Write time: 216528 09:17:04 INFO - Running post-run listener: _upload_blobber_files 09:17:04 INFO - Blob upload gear active. 09:17:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:17:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:17:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:17:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:17:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:17:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:06 INFO - (blobuploader) - INFO - Done attempting. 09:17:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:17:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:17:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:07 INFO - (blobuploader) - INFO - Done attempting. 09:17:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:17:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:10 INFO - (blobuploader) - INFO - Done attempting. 09:17:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:17:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:17:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:11 INFO - (blobuploader) - INFO - Done attempting. 09:17:11 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:11 INFO - Return code: 0 09:17:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ee2fb436b4d21d95febbd3705e48f3c9d94dfaf55affd454a65773549873c2f97be333558fbbbda09e3f51c9d9d1e8245c5f9ad936573693bbb93faa6d7ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42de66e16adc86626427c0388d570d90f31cc9833c33e53444e2687cb9a3d3b0ced48777f7f9664483d935621892c47bbfd8a1fdbecc910ee6245573bebfddcb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd32cd7178e2644439d436b364ce632813e7653208a8b9d90a0db635e898f38d49258e8cd3693dd326a7e04c53da7468a2e6fbb74cacd6d74dedb97094c0d464", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bead73465dd422e51aae7f82d43b348cc289abcdc1051d8793c802519f3e52af9ad3657c80025736fa7ca37d3c755ade36722b308b6c53a562555d295cb25e4"} 09:17:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:11 INFO - Contents: 09:17:11 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ee2fb436b4d21d95febbd3705e48f3c9d94dfaf55affd454a65773549873c2f97be333558fbbbda09e3f51c9d9d1e8245c5f9ad936573693bbb93faa6d7ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42de66e16adc86626427c0388d570d90f31cc9833c33e53444e2687cb9a3d3b0ced48777f7f9664483d935621892c47bbfd8a1fdbecc910ee6245573bebfddcb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd32cd7178e2644439d436b364ce632813e7653208a8b9d90a0db635e898f38d49258e8cd3693dd326a7e04c53da7468a2e6fbb74cacd6d74dedb97094c0d464", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bead73465dd422e51aae7f82d43b348cc289abcdc1051d8793c802519f3e52af9ad3657c80025736fa7ca37d3c755ade36722b308b6c53a562555d295cb25e4"} 09:17:11 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:11 INFO - Copying logs to upload dir... 09:17:11 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.774535 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-05-05 09:17:11.695841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 09:17:11.700643) ========= 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=826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ee2fb436b4d21d95febbd3705e48f3c9d94dfaf55affd454a65773549873c2f97be333558fbbbda09e3f51c9d9d1e8245c5f9ad936573693bbb93faa6d7ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42de66e16adc86626427c0388d570d90f31cc9833c33e53444e2687cb9a3d3b0ced48777f7f9664483d935621892c47bbfd8a1fdbecc910ee6245573bebfddcb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd32cd7178e2644439d436b364ce632813e7653208a8b9d90a0db635e898f38d49258e8cd3693dd326a7e04c53da7468a2e6fbb74cacd6d74dedb97094c0d464", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bead73465dd422e51aae7f82d43b348cc289abcdc1051d8793c802519f3e52af9ad3657c80025736fa7ca37d3c755ade36722b308b6c53a562555d295cb25e4"} build_url:https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/CHjEs6ExRWqh7F70WG6u8w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89ee2fb436b4d21d95febbd3705e48f3c9d94dfaf55affd454a65773549873c2f97be333558fbbbda09e3f51c9d9d1e8245c5f9ad936573693bbb93faa6d7ac9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42de66e16adc86626427c0388d570d90f31cc9833c33e53444e2687cb9a3d3b0ced48777f7f9664483d935621892c47bbfd8a1fdbecc910ee6245573bebfddcb", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd32cd7178e2644439d436b364ce632813e7653208a8b9d90a0db635e898f38d49258e8cd3693dd326a7e04c53da7468a2e6fbb74cacd6d74dedb97094c0d464", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4bead73465dd422e51aae7f82d43b348cc289abcdc1051d8793c802519f3e52af9ad3657c80025736fa7ca37d3c755ade36722b308b6c53a562555d295cb25e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 09:17:11.729522) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 09:17:11.729854) ========= 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=826874ccc97bd7bf541b4875000001d5-1493999241.738458-851856605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 09:17:11.809030) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 09:17:11.809356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 09:17:11.809659) ========= ========= Total master_lag: 0.25 =========