builder: pine_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-078 starttime: 1490321262.75 results: success (0) revision: 5117704c9361e4b9a3eee82d1ce4a9d93222e6be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:42.747566) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:42.748031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:42.748337) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:42.763188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:42.763481) ========= 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=dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-23 19:07:42-- 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% 46.6M=0s 2017-03-23 19:07:43 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.331717 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:43.109382) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:43.109727) ========= 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=dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013564 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:43.159072) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 19:07:43.159430) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 5117704c9361e4b9a3eee82d1ce4a9d93222e6be --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/pine --rev 5117704c9361e4b9a3eee82d1ce4a9d93222e6be --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=dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-23 19:07:43,341 truncating revision to first 12 chars 2017-03-23 19:07:43,341 Setting DEBUG logging. 2017-03-23 19:07:43,341 attempt 1/10 2017-03-23 19:07:43,341 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/5117704c9361?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 19:07:44,469 unpacking tar archive at: pine-5117704c9361/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492496 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 19:07:44.666939) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:44.667315) ========= script_repo_revision: 5117704c9361e4b9a3eee82d1ce4a9d93222e6be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:44.667721) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:44.668033) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 19:07:44.678887) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-23 19:07:44.679200) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] 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=dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:07:44 INFO - MultiFileLogger online at 20170323 19:07:44 in /builds/slave/test 19:07:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 19:07:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:44 INFO - {'append_to_log': False, 19:07:44 INFO - 'base_work_dir': '/builds/slave/test', 19:07:44 INFO - 'blob_upload_branch': 'Pine', 19:07:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:44 INFO - 'branch': 'Pine', 19:07:44 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:44 INFO - 'config_files': ('talos/linux_config.py',), 19:07:44 INFO - 'default_actions': ('clobber', 19:07:44 INFO - 'read-buildbot-config', 19:07:44 INFO - 'download-and-extract', 19:07:44 INFO - 'populate-webroot', 19:07:44 INFO - 'create-virtualenv', 19:07:44 INFO - 'install', 19:07:44 INFO - 'run-tests'), 19:07:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:44 INFO - 'download_minidump_stackwalk': True, 19:07:44 INFO - 'download_symbols': 'ondemand', 19:07:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:44 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:44 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:44 INFO - 'gecko_profile': False, 19:07:44 INFO - 'gecko_profile_interval': 0, 19:07:44 INFO - 'installer_path': 'installer.exe', 19:07:44 INFO - 'log_level': 'info', 19:07:44 INFO - 'log_name': 'talos', 19:07:44 INFO - 'log_to_console': True, 19:07:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:07:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:07:44 INFO - 'opt_config_files': (), 19:07:44 INFO - 'pip_index': False, 19:07:44 INFO - 'suite': 'dromaeojs', 19:07:44 INFO - 'system_bits': '32', 19:07:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:44 INFO - 'title': 'talos-linux64-ix-078', 19:07:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:44 INFO - 'use_talos_json': True, 19:07:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:44 INFO - 'work_dir': 'build'} 19:07:44 INFO - [mozharness: 2017-03-24 02:07:44.953840Z] Running clobber step. 19:07:44 INFO - Running pre-action listener: _resource_record_pre_action 19:07:44 INFO - Running main action method: clobber 19:07:44 INFO - rmtree: /builds/slave/test/build 19:07:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:45 INFO - Running post-action listener: _resource_record_post_action 19:07:45 INFO - [mozharness: 2017-03-24 02:07:45.938883Z] Finished clobber step (success) 19:07:45 INFO - [mozharness: 2017-03-24 02:07:45.938985Z] Running read-buildbot-config step. 19:07:45 INFO - Running pre-action listener: _resource_record_pre_action 19:07:45 INFO - Running main action method: read_buildbot_config 19:07:45 INFO - Using buildbot properties: 19:07:45 INFO - { 19:07:45 INFO - "project": "", 19:07:45 INFO - "product": "firefox", 19:07:45 INFO - "who": "dmosedale@mozilla.com", 19:07:45 INFO - "installer_path": "public/build/target.tar.bz2", 19:07:45 INFO - "builddir": "pine_ubuntu64_hw_test-dromaeojs", 19:07:45 INFO - "repository": "https://hg.mozilla.org/projects/pine/", 19:07:45 INFO - "buildername": "Ubuntu HW 12.04 x64 pine talos dromaeojs", 19:07:45 INFO - "stage_platform": "linux64", 19:07:45 INFO - "basedir": "/builds/slave/test", 19:07:45 INFO - "buildnumber": 0, 19:07:45 INFO - "platform": "ubuntu64_hw", 19:07:45 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:07:45 INFO - "slavebuilddir": "test", 19:07:45 INFO - "taskId": "SUo0vD4BS-m5_DcwYKROeQ", 19:07:45 INFO - "branch": "pine", 19:07:45 INFO - "script_repo_revision": "default", 19:07:45 INFO - "revision": "5117704c9361e4b9a3eee82d1ce4a9d93222e6be", 19:07:45 INFO - "slavename": "talos-linux64-ix-078", 19:07:45 INFO - "repo_path": "projects/pine" 19:07:45 INFO - } 19:07:45 INFO - Finding installer, test and symbols from parent task. 19:07:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUo0vD4BS-m5_DcwYKROeQ'}, attempt #1 19:07:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUo0vD4BS-m5_DcwYKROeQ'}, attempt #1 19:07:46 INFO - Task dependencies: En4_ybPfRAirG61usP7e7w 19:07:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w'}, attempt #1 19:07:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2 19:07:47 INFO - Set test_url: https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/test_packages.json 19:07:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.crashreporter-symbols.zip 19:07:47 INFO - Running post-action listener: _resource_record_post_action 19:07:47 INFO - [mozharness: 2017-03-24 02:07:47.374906Z] Finished read-buildbot-config step (success) 19:07:47 INFO - [mozharness: 2017-03-24 02:07:47.375125Z] Running download-and-extract step. 19:07:47 INFO - Running pre-action listener: _resource_record_pre_action 19:07:47 INFO - Running main action method: download_and_extract 19:07:47 INFO - mkdir: /builds/slave/test/build/tests 19:07:47 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')]} 19:07:47 INFO - trying https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.test_packages.json 19:07:47 INFO - Downloading https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:07:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:07:47 INFO - Downloaded 986 bytes. 19:07:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:07:47 INFO - Using the following test package requirements: 19:07:47 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:07:47 INFO - u'common': [u'target.common.tests.zip'], 19:07:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:07:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:07:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:07:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:07:47 INFO - u'mozbase': [u'target.common.tests.zip'], 19:07:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:07:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:07:47 INFO - u'web-platform': [u'target.common.tests.zip', 19:07:47 INFO - u'target.web-platform.tests.zip'], 19:07:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:07:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:07:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.common.tests.zip 19:07:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:07:47 INFO - Fetch https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.common.tests.zip into memory 19:07:49 INFO - Expected file size: 58125554 19:07:49 INFO - Obtained file size: 58125554 19:07:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:07:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.common.tests.zip 19:07:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:07:58 INFO - Fetch https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.common.tests.zip into memory 19:08:00 INFO - Expected file size: 58125554 19:08:00 INFO - Obtained file size: 58125554 19:08:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.talos.tests.zip 19:08:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:08:10 INFO - Fetch https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.talos.tests.zip into memory 19:08:11 INFO - Expected file size: 14404171 19:08:11 INFO - Obtained file size: 14404171 19:08: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')]} 19:08:11 INFO - trying https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2 19:08:11 INFO - Downloading https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:08:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:08:12 INFO - Downloaded 62476328 bytes. 19:08:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2 19:08:12 INFO - mkdir: /builds/slave/test/properties 19:08:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:08:12 INFO - Writing to file /builds/slave/test/properties/build_url 19:08:12 INFO - Contents: 19:08:12 INFO - build_url:https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2 19:08:12 INFO - Running post-action listener: _resource_record_post_action 19:08:12 INFO - Running post-action listener: set_extra_try_arguments 19:08:12 INFO - [mozharness: 2017-03-24 02:08:12.982302Z] Finished download-and-extract step (success) 19:08:12 INFO - [mozharness: 2017-03-24 02:08:12.982398Z] Running populate-webroot step. 19:08:12 INFO - Running pre-action listener: _resource_record_pre_action 19:08:12 INFO - Running main action method: populate_webroot 19:08:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:08:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:08:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:08:12 INFO - '--tppagecycles', 19:08:12 INFO - '7'], 19:08:12 INFO - 'tests': ['tsvgm']}}, 19:08:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:08:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:08:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:08:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:08:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['damp', 'tps']}, 19:08:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'tests': ['damp', 'tps']}, 19:08:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['dromaeo_dom']}, 19:08:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:08:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['basic_compositor_video']}, 19:08:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:08:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['a11yr', 19:08:12 INFO - 'ts_paint', 19:08:12 INFO - 'tpaint', 19:08:12 INFO - 'sessionrestore', 19:08:12 INFO - 'sessionrestore_no_auto_restore', 19:08:12 INFO - 'tabpaint']}, 19:08:12 INFO - 'other-e10s': {'tests': ['a11yr', 19:08:12 INFO - 'ts_paint', 19:08:12 INFO - 'tpaint', 19:08:12 INFO - 'sessionrestore', 19:08:12 INFO - 'sessionrestore_no_auto_restore', 19:08:12 INFO - 'tabpaint']}, 19:08:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:08:12 INFO - 'ts_paint', 19:08:12 INFO - 'tpaint', 19:08:12 INFO - 'sessionrestore', 19:08:12 INFO - 'sessionrestore_no_auto_restore', 19:08:12 INFO - 'tabpaint']}, 19:08:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:08:12 INFO - 'ts_paint', 19:08:12 INFO - 'tpaint', 19:08:12 INFO - 'sessionrestore', 19:08:12 INFO - 'sessionrestore_no_auto_restore', 19:08:12 INFO - 'tabpaint']}, 19:08:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['a11yr', 19:08:12 INFO - 'ts_paint', 19:08:12 INFO - 'tpaint', 19:08:12 INFO - 'sessionrestore', 19:08:12 INFO - 'sessionrestore_no_auto_restore', 19:08:12 INFO - 'tabpaint']}, 19:08:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['a11yr', 19:08:12 INFO - 'ts_paint', 19:08:12 INFO - 'tpaint', 19:08:12 INFO - 'sessionrestore', 19:08:12 INFO - 'sessionrestore_no_auto_restore', 19:08:12 INFO - 'tabpaint']}, 19:08:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['tsvgx', 19:08:12 INFO - 'tsvgr_opacity', 19:08:12 INFO - 'tart', 19:08:12 INFO - 'tscrollx', 19:08:12 INFO - 'cart', 19:08:12 INFO - 'tsvg_static']}, 19:08:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:08:12 INFO - 'tsvgr_opacity', 19:08:12 INFO - 'tart', 19:08:12 INFO - 'tscrollx', 19:08:12 INFO - 'cart', 19:08:12 INFO - 'tsvg_static']}, 19:08:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'talos_options': ['--disable-e10s'], 19:08:12 INFO - 'tests': ['tp5o']}, 19:08:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:08:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'talos_options': ['--disable-e10s', 19:08:12 INFO - '--xperf_path', 19:08:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:12 INFO - 'tests': ['tp5n']}, 19:08:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:08:12 INFO - 'talos_options': ['--xperf_path', 19:08:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:12 INFO - 'tests': ['tp5n']}}, 19:08:12 INFO - 'talos.zip': {'path': '', 19:08:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:08:12 INFO - Running post-action listener: _resource_record_post_action 19:08:12 INFO - [mozharness: 2017-03-24 02:08:12.995580Z] Finished populate-webroot step (success) 19:08:12 INFO - [mozharness: 2017-03-24 02:08:12.995671Z] Running create-virtualenv step. 19:08:12 INFO - Running pre-action listener: _resource_record_pre_action 19:08:12 INFO - Running main action method: create_virtualenv 19:08:12 INFO - Creating virtualenv /builds/slave/test/build/venv 19:08:12 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 19:08:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:08:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:08:13 INFO - Using real prefix '/usr' 19:08:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:08:13 INFO - Installing distribute.............................................................................................................................................................................................done. 19:08:15 INFO - Installing pip.................done. 19:08:15 INFO - Return code: 0 19:08:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:08:15 INFO - Reading from file tmpfile_stdout 19:08:15 INFO - Output received: 19:08:15 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) 19:08:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:08:15 INFO - Using env: {'DISPLAY': ':0', 19:08:15 INFO - 'HOME': '/home/cltbld', 19:08:15 INFO - 'LANG': 'en_US.UTF-8', 19:08:15 INFO - 'LANGUAGE': 'en_US:en', 19:08:15 INFO - 'LOGNAME': 'cltbld', 19:08:15 INFO - 'MAIL': '/var/mail/cltbld', 19:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:15 INFO - 'MOZ_NO_REMOTE': '1', 19:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:15 INFO - 'NO_EM_RESTART': '1', 19:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:15 INFO - 'PWD': '/builds/slave/test', 19:08:15 INFO - 'SHELL': '/bin/bash', 19:08:15 INFO - 'SHLVL': '1', 19:08:15 INFO - 'TERM': 'linux', 19:08:15 INFO - 'TMOUT': '86400', 19:08:15 INFO - 'USER': 'cltbld', 19:08:15 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:15 INFO - '_': '/tools/buildbot/bin/python'} 19:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:15 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 19:08:15 INFO - Cleaning up... 19:08:15 INFO - Return code: 0 19:08:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:08:16 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')]} 19:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:16 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')]} 19:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:08:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:08:16 INFO - Using env: {'DISPLAY': ':0', 19:08:16 INFO - 'HOME': '/home/cltbld', 19:08:16 INFO - 'LANG': 'en_US.UTF-8', 19:08:16 INFO - 'LANGUAGE': 'en_US:en', 19:08:16 INFO - 'LOGNAME': 'cltbld', 19:08:16 INFO - 'MAIL': '/var/mail/cltbld', 19:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:16 INFO - 'MOZ_NO_REMOTE': '1', 19:08:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:16 INFO - 'NO_EM_RESTART': '1', 19:08:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:16 INFO - 'PWD': '/builds/slave/test', 19:08:16 INFO - 'SHELL': '/bin/bash', 19:08:16 INFO - 'SHLVL': '1', 19:08:16 INFO - 'TERM': 'linux', 19:08:16 INFO - 'TMOUT': '86400', 19:08:16 INFO - 'USER': 'cltbld', 19:08:16 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:16 INFO - '_': '/tools/buildbot/bin/python'} 19:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:16 INFO - Downloading/unpacking psutil>=3.1.1 19:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:08:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:18 INFO - Installing collected packages: psutil 19:08:18 INFO - Running setup.py install for psutil 19:08:18 INFO - building 'psutil._psutil_linux' extension 19:08:18 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 19:08:18 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 19:08:18 INFO - building 'psutil._psutil_posix' extension 19:08:18 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 19:08:18 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 19:08:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:18 INFO - Successfully installed psutil 19:08:18 INFO - Cleaning up... 19:08:18 INFO - Return code: 0 19:08:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:08:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:08:18 INFO - Using env: {'DISPLAY': ':0', 19:08:18 INFO - 'HOME': '/home/cltbld', 19:08:18 INFO - 'LANG': 'en_US.UTF-8', 19:08:18 INFO - 'LANGUAGE': 'en_US:en', 19:08:18 INFO - 'LOGNAME': 'cltbld', 19:08:18 INFO - 'MAIL': '/var/mail/cltbld', 19:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:18 INFO - 'MOZ_NO_REMOTE': '1', 19:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:18 INFO - 'NO_EM_RESTART': '1', 19:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:18 INFO - 'PWD': '/builds/slave/test', 19:08:18 INFO - 'SHELL': '/bin/bash', 19:08:18 INFO - 'SHLVL': '1', 19:08:18 INFO - 'TERM': 'linux', 19:08:18 INFO - 'TMOUT': '86400', 19:08:18 INFO - 'USER': 'cltbld', 19:08:18 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:18 INFO - '_': '/tools/buildbot/bin/python'} 19:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:08:19 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 19:08:19 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 19:08:19 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 19:08:19 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 19:08:19 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 19:08:19 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 19:08:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:08:21 INFO - Installing collected packages: mozsystemmonitor 19:08:21 INFO - Running setup.py install for mozsystemmonitor 19:08:21 INFO - Successfully installed mozsystemmonitor 19:08:21 INFO - Cleaning up... 19:08:21 INFO - Return code: 0 19:08:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:08:21 INFO - Using env: {'DISPLAY': ':0', 19:08:21 INFO - 'HOME': '/home/cltbld', 19:08:21 INFO - 'LANG': 'en_US.UTF-8', 19:08:21 INFO - 'LANGUAGE': 'en_US:en', 19:08:21 INFO - 'LOGNAME': 'cltbld', 19:08:21 INFO - 'MAIL': '/var/mail/cltbld', 19:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:21 INFO - 'MOZ_NO_REMOTE': '1', 19:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:21 INFO - 'NO_EM_RESTART': '1', 19:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:21 INFO - 'PWD': '/builds/slave/test', 19:08:21 INFO - 'SHELL': '/bin/bash', 19:08:21 INFO - 'SHLVL': '1', 19:08:21 INFO - 'TERM': 'linux', 19:08:21 INFO - 'TMOUT': '86400', 19:08:21 INFO - 'USER': 'cltbld', 19:08:21 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:21 INFO - '_': '/tools/buildbot/bin/python'} 19:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:21 INFO - Downloading/unpacking jsonschema==2.5.1 19:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:08:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:23 INFO - Downloading functools32-3.2.3-2.tar.gz 19:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:08:23 INFO - warning: no files found matching '*.txt' 19:08:23 INFO - no previously-included directories found matching 'build' 19:08:23 INFO - no previously-included directories found matching 'dist' 19:08:23 INFO - no previously-included directories found matching '.git*' 19:08:23 INFO - Installing collected packages: jsonschema, functools32 19:08:23 INFO - Running setup.py install for functools32 19:08:23 INFO - warning: no files found matching '*.txt' 19:08:23 INFO - no previously-included directories found matching 'build' 19:08:23 INFO - no previously-included directories found matching 'dist' 19:08:23 INFO - no previously-included directories found matching '.git*' 19:08:23 INFO - Successfully installed jsonschema functools32 19:08:23 INFO - Cleaning up... 19:08:24 INFO - Return code: 0 19:08:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:24 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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:24 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 19:08:24 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 19:08:24 INFO - Using env: {'DISPLAY': ':0', 19:08:24 INFO - 'HOME': '/home/cltbld', 19:08:24 INFO - 'LANG': 'en_US.UTF-8', 19:08:24 INFO - 'LANGUAGE': 'en_US:en', 19:08:24 INFO - 'LOGNAME': 'cltbld', 19:08:24 INFO - 'MAIL': '/var/mail/cltbld', 19:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:24 INFO - 'MOZ_NO_REMOTE': '1', 19:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:24 INFO - 'NO_EM_RESTART': '1', 19:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:24 INFO - 'PWD': '/builds/slave/test', 19:08:24 INFO - 'SHELL': '/bin/bash', 19:08:24 INFO - 'SHLVL': '1', 19:08:24 INFO - 'TERM': 'linux', 19:08:24 INFO - 'TMOUT': '86400', 19:08:24 INFO - 'USER': 'cltbld', 19:08:24 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:24 INFO - '_': '/tools/buildbot/bin/python'} 19:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:08:24 INFO - Cleaning up... 19:08:24 INFO - Return code: 0 19:08:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:24 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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:24 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 19:08:24 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 19:08:24 INFO - Using env: {'DISPLAY': ':0', 19:08:24 INFO - 'HOME': '/home/cltbld', 19:08:24 INFO - 'LANG': 'en_US.UTF-8', 19:08:24 INFO - 'LANGUAGE': 'en_US:en', 19:08:24 INFO - 'LOGNAME': 'cltbld', 19:08:24 INFO - 'MAIL': '/var/mail/cltbld', 19:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:24 INFO - 'MOZ_NO_REMOTE': '1', 19:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:24 INFO - 'NO_EM_RESTART': '1', 19:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:24 INFO - 'PWD': '/builds/slave/test', 19:08:24 INFO - 'SHELL': '/bin/bash', 19:08:24 INFO - 'SHLVL': '1', 19:08:24 INFO - 'TERM': 'linux', 19:08:24 INFO - 'TMOUT': '86400', 19:08:24 INFO - 'USER': 'cltbld', 19:08:24 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:24 INFO - '_': '/tools/buildbot/bin/python'} 19:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:24 INFO - Downloading/unpacking blobuploader==1.2.4 19:08:24 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 19:08:24 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 19:08:24 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 19:08:24 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 19:08:24 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 19:08:24 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 19:08:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:08:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:08:26 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 19:08:26 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 19:08:26 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 19:08:26 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 19:08:26 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 19:08:26 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 19:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:08:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:08:27 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 19:08:27 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 19:08:27 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 19:08:27 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 19:08:27 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 19:08:27 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 19:08:27 INFO - Downloading docopt-0.6.1.tar.gz 19:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:08:27 INFO - Installing collected packages: blobuploader, requests, docopt 19:08:27 INFO - Running setup.py install for blobuploader 19:08:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:08:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:08:27 INFO - Running setup.py install for requests 19:08:27 INFO - Running setup.py install for docopt 19:08:27 INFO - Successfully installed blobuploader requests docopt 19:08:27 INFO - Cleaning up... 19:08:27 INFO - Return code: 0 19:08:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:27 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')]} 19:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:27 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')]} 19:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:27 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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:27 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 19:08:27 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 19:08:27 INFO - Using env: {'DISPLAY': ':0', 19:08:27 INFO - 'HOME': '/home/cltbld', 19:08:27 INFO - 'LANG': 'en_US.UTF-8', 19:08:27 INFO - 'LANGUAGE': 'en_US:en', 19:08:27 INFO - 'LOGNAME': 'cltbld', 19:08:27 INFO - 'MAIL': '/var/mail/cltbld', 19:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:27 INFO - 'MOZ_NO_REMOTE': '1', 19:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:27 INFO - 'NO_EM_RESTART': '1', 19:08:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:27 INFO - 'PWD': '/builds/slave/test', 19:08:27 INFO - 'SHELL': '/bin/bash', 19:08:27 INFO - 'SHLVL': '1', 19:08:27 INFO - 'TERM': 'linux', 19:08:27 INFO - 'TMOUT': '86400', 19:08:27 INFO - 'USER': 'cltbld', 19:08:27 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:27 INFO - '_': '/tools/buildbot/bin/python'} 19:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:28 INFO - Running setup.py (path:/tmp/pip-rpHCqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:28 INFO - Running setup.py (path:/tmp/pip-sm9Mmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:28 INFO - Running setup.py (path:/tmp/pip-qAHJRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:28 INFO - Running setup.py (path:/tmp/pip-25jMvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:28 INFO - Running setup.py (path:/tmp/pip-Hero5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:28 INFO - Running setup.py (path:/tmp/pip-G4m9I_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:28 INFO - Running setup.py (path:/tmp/pip-HVdHG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:28 INFO - Running setup.py (path:/tmp/pip-BeQK0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:28 INFO - Running setup.py (path:/tmp/pip-hf4GoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:28 INFO - Running setup.py (path:/tmp/pip-XCuVO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:28 INFO - Running setup.py (path:/tmp/pip-pHyVTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:28 INFO - Running setup.py (path:/tmp/pip-h3walL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:29 INFO - Running setup.py (path:/tmp/pip-5BXhS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:29 INFO - Running setup.py (path:/tmp/pip-0D00ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:29 INFO - Running setup.py (path:/tmp/pip-lPINp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:29 INFO - Running setup.py (path:/tmp/pip-gWTZql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:29 INFO - Running setup.py (path:/tmp/pip-rd02x3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:08:29 INFO - Running setup.py install for manifestparser 19:08:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:08:29 INFO - Running setup.py install for mozcrash 19:08:29 INFO - Running setup.py install for mozdebug 19:08:29 INFO - Running setup.py install for mozdevice 19:08:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:08:29 INFO - Running setup.py install for mozfile 19:08:29 INFO - Running setup.py install for mozhttpd 19:08:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:08:29 INFO - Running setup.py install for mozinfo 19:08:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Running setup.py install for mozInstall 19:08:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Running setup.py install for mozleak 19:08:30 INFO - Running setup.py install for mozlog 19:08:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Running setup.py install for moznetwork 19:08:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Running setup.py install for mozprocess 19:08:30 INFO - Running setup.py install for mozprofile 19:08:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Running setup.py install for mozrunner 19:08:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:08:30 INFO - Running setup.py install for mozscreenshot 19:08:30 INFO - Running setup.py install for moztest 19:08:30 INFO - Running setup.py install for mozversion 19:08:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:08:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:08:31 INFO - Cleaning up... 19:08:31 INFO - Return code: 0 19:08:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:31 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')]} 19:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:31 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')]} 19:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:31 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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:31 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 19:08:31 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 19:08:31 INFO - Using env: {'DISPLAY': ':0', 19:08:31 INFO - 'HOME': '/home/cltbld', 19:08:31 INFO - 'LANG': 'en_US.UTF-8', 19:08:31 INFO - 'LANGUAGE': 'en_US:en', 19:08:31 INFO - 'LOGNAME': 'cltbld', 19:08:31 INFO - 'MAIL': '/var/mail/cltbld', 19:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:31 INFO - 'MOZ_NO_REMOTE': '1', 19:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:31 INFO - 'NO_EM_RESTART': '1', 19:08:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:31 INFO - 'PWD': '/builds/slave/test', 19:08:31 INFO - 'SHELL': '/bin/bash', 19:08:31 INFO - 'SHLVL': '1', 19:08:31 INFO - 'TERM': 'linux', 19:08:31 INFO - 'TMOUT': '86400', 19:08:31 INFO - 'USER': 'cltbld', 19:08:31 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:31 INFO - '_': '/tools/buildbot/bin/python'} 19:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:31 INFO - Running setup.py (path:/tmp/pip-3WJGj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:31 INFO - Running setup.py (path:/tmp/pip-Ad1GIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:31 INFO - Running setup.py (path:/tmp/pip-NOW7e0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:31 INFO - Running setup.py (path:/tmp/pip-_lK_xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:31 INFO - Running setup.py (path:/tmp/pip-RM599Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:31 INFO - Running setup.py (path:/tmp/pip-7DusUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:31 INFO - Running setup.py (path:/tmp/pip-4Bgn6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:31 INFO - Running setup.py (path:/tmp/pip-Q7E_tp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:31 INFO - Running setup.py (path:/tmp/pip-MtfhNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:31 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)) 19:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:32 INFO - Running setup.py (path:/tmp/pip-MIt3eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:32 INFO - Running setup.py (path:/tmp/pip-6ZMtgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:32 INFO - Running setup.py (path:/tmp/pip-0g_pSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:32 INFO - Running setup.py (path:/tmp/pip-pvfEjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:32 INFO - Running setup.py (path:/tmp/pip-hwwGDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:32 INFO - Running setup.py (path:/tmp/pip-DhHgOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:32 INFO - Running setup.py (path:/tmp/pip-Ehg7Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:32 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)) 19:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:32 INFO - Running setup.py (path:/tmp/pip-7oHV7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:32 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)) 19:08:32 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)) 19:08:32 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)) 19:08:32 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)) 19:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:32 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 19:08:32 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 19:08:32 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 19:08:32 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 19:08:32 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 19:08:32 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 19:08:34 INFO - Downloading blessings-1.6.tar.gz 19:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:08:34 INFO - Installing collected packages: blessings 19:08:34 INFO - Running setup.py install for blessings 19:08:34 INFO - Successfully installed blessings 19:08:34 INFO - Cleaning up... 19:08:34 INFO - Return code: 0 19:08:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:08:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:35 INFO - Reading from file tmpfile_stdout 19:08:35 INFO - Current package versions: 19:08:35 INFO - argparse == 1.2.1 19:08:35 INFO - blessings == 1.6 19:08:35 INFO - blobuploader == 1.2.4 19:08:35 INFO - docopt == 0.6.1 19:08:35 INFO - functools32 == 3.2.3-2 19:08:35 INFO - jsonschema == 2.5.1 19:08:35 INFO - manifestparser == 1.1 19:08:35 INFO - mozInstall == 1.12 19:08:35 INFO - mozcrash == 1.0 19:08:35 INFO - mozdebug == 0.1 19:08:35 INFO - mozdevice == 0.49 19:08:35 INFO - mozfile == 1.2 19:08:35 INFO - mozhttpd == 0.7 19:08:35 INFO - mozinfo == 0.9 19:08:35 INFO - mozleak == 0.1 19:08:35 INFO - mozlog == 3.4 19:08:35 INFO - moznetwork == 0.27 19:08:35 INFO - mozprocess == 0.25 19:08:35 INFO - mozprofile == 0.28 19:08:35 INFO - mozrunner == 6.13 19:08:35 INFO - mozscreenshot == 0.1 19:08:35 INFO - mozsystemmonitor == 0.3 19:08:35 INFO - moztest == 0.8 19:08:35 INFO - mozversion == 1.4 19:08:35 INFO - psutil == 3.1.1 19:08:35 INFO - requests == 1.2.3 19:08:35 INFO - wsgiref == 0.1.2 19:08:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:35 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')]} 19:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:35 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')]} 19:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:35 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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:35 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 19:08:35 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 19:08:35 INFO - Using env: {'DISPLAY': ':0', 19:08:35 INFO - 'HOME': '/home/cltbld', 19:08:35 INFO - 'LANG': 'en_US.UTF-8', 19:08:35 INFO - 'LANGUAGE': 'en_US:en', 19:08:35 INFO - 'LOGNAME': 'cltbld', 19:08:35 INFO - 'MAIL': '/var/mail/cltbld', 19:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:35 INFO - 'MOZ_NO_REMOTE': '1', 19:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:35 INFO - 'NO_EM_RESTART': '1', 19:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:35 INFO - 'PWD': '/builds/slave/test', 19:08:35 INFO - 'SHELL': '/bin/bash', 19:08:35 INFO - 'SHLVL': '1', 19:08:35 INFO - 'TERM': 'linux', 19:08:35 INFO - 'TMOUT': '86400', 19:08:35 INFO - 'USER': 'cltbld', 19:08:35 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:35 INFO - '_': '/tools/buildbot/bin/python'} 19:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 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)) 19:08:35 INFO - Cleaning up... 19:08:35 INFO - Return code: 0 19:08:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:08:35 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')]} 19:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:35 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')]} 19:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:35 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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd4ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfad310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, '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': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', '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 19:08:35 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 19:08:35 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 19:08:35 INFO - Using env: {'DISPLAY': ':0', 19:08:35 INFO - 'HOME': '/home/cltbld', 19:08:35 INFO - 'LANG': 'en_US.UTF-8', 19:08:35 INFO - 'LANGUAGE': 'en_US:en', 19:08:35 INFO - 'LOGNAME': 'cltbld', 19:08:35 INFO - 'MAIL': '/var/mail/cltbld', 19:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:35 INFO - 'MOZ_NO_REMOTE': '1', 19:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:35 INFO - 'NO_EM_RESTART': '1', 19:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:35 INFO - 'PWD': '/builds/slave/test', 19:08:35 INFO - 'SHELL': '/bin/bash', 19:08:35 INFO - 'SHLVL': '1', 19:08:35 INFO - 'TERM': 'linux', 19:08:35 INFO - 'TMOUT': '86400', 19:08:35 INFO - 'USER': 'cltbld', 19:08:35 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:35 INFO - '_': '/tools/buildbot/bin/python'} 19:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:08:35 INFO - Cleaning up... 19:08:35 INFO - Return code: 0 19:08:35 INFO - Running post-action listener: _resource_record_post_action 19:08:35 INFO - Running post-action listener: _start_resource_monitoring 19:08:35 INFO - Starting resource monitoring. 19:08:35 INFO - [mozharness: 2017-03-24 02:08:35.545478Z] Finished create-virtualenv step (success) 19:08:35 INFO - [mozharness: 2017-03-24 02:08:35.545989Z] Running install step. 19:08:35 INFO - Running pre-action listener: _resource_record_pre_action 19:08:35 INFO - Running main action method: install 19:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:35 INFO - Reading from file tmpfile_stdout 19:08:35 INFO - Detecting whether we're running mozinstall >=1.0... 19:08:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:08:35 INFO - Reading from file tmpfile_stdout 19:08:35 INFO - Output received: 19:08:35 INFO - Usage: mozinstall [options] installer 19:08:35 INFO - Options: 19:08:35 INFO - -h, --help show this help message and exit 19:08:35 INFO - -d DEST, --destination=DEST 19:08:35 INFO - Directory to install application into. [default: 19:08:35 INFO - "/builds/slave/test"] 19:08:35 INFO - --app=APP Application being installed. [default: firefox] 19:08:35 INFO - mkdir: /builds/slave/test/build/application 19:08:35 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'] 19:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:08:51 INFO - Reading from file tmpfile_stdout 19:08:51 INFO - Output received: 19:08:51 INFO - /builds/slave/test/build/application/firefox/firefox 19:08:51 INFO - Running post-action listener: _resource_record_post_action 19:08:51 INFO - [mozharness: 2017-03-24 02:08:51.495793Z] Finished install step (success) 19:08:51 INFO - [mozharness: 2017-03-24 02:08:51.495937Z] Running run-tests step. 19:08:51 INFO - Running pre-action listener: _resource_record_pre_action 19:08:51 INFO - Running main action method: run_tests 19:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:08:51 INFO - Python 2.7.3 19:08:51 INFO - Return code: 0 19:08:51 INFO - grabbing minidump binary from tooltool 19:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:51 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 0xfaa400>, '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 0xfab3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:08:51 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 19:08:51 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 19:08:51 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 19:08:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:08:51 INFO - Return code: 0 19:08:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:08:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:08:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:08:51 INFO - ENV: RUST_BACKTRACE is now 1 19:08:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:08:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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 19:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-078 --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 19:08:51 INFO - Using env: {'DISPLAY': ':0', 19:08:51 INFO - 'HOME': '/home/cltbld', 19:08:51 INFO - 'LANG': 'en_US.UTF-8', 19:08:51 INFO - 'LANGUAGE': 'en_US:en', 19:08:51 INFO - 'LOGNAME': 'cltbld', 19:08:51 INFO - 'MAIL': '/var/mail/cltbld', 19:08:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:08:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:51 INFO - 'MOZ_NO_REMOTE': '1', 19:08:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:51 INFO - 'NO_EM_RESTART': '1', 19:08:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:51 INFO - 'PWD': '/builds/slave/test', 19:08:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:08:51 INFO - 'RUST_BACKTRACE': '1', 19:08:51 INFO - 'SHELL': '/bin/bash', 19:08:51 INFO - 'SHLVL': '1', 19:08:51 INFO - 'TERM': 'linux', 19:08:51 INFO - 'TMOUT': '86400', 19:08:51 INFO - 'USER': 'cltbld', 19:08:51 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523', 19:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:51 INFO - '_': '/tools/buildbot/bin/python'} 19:08:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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 19:08:51 INFO - mozversion application_buildid: 20170323221417 19:08:51 INFO - mozversion application_changeset: 5117704c9361e4b9a3eee82d1ce4a9d93222e6be 19:08:51 INFO - mozversion application_display_name: Nightly 19:08:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:51 INFO - mozversion application_name: Firefox 19:08:51 INFO - mozversion application_remotingname: firefox 19:08:51 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 19:08:51 INFO - mozversion application_vendor: Mozilla 19:08:51 INFO - mozversion application_version: 55.0a1 19:08:51 INFO - mozversion platform_buildid: 20170323221417 19:08:51 INFO - mozversion platform_changeset: 5117704c9361e4b9a3eee82d1ce4a9d93222e6be 19:08:51 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 19:08:51 INFO - mozversion platform_version: 55.0a1 19:08:51 INFO - using testdate: 1490321331 19:08:51 INFO - actual date: 1490321331 19:08:51 INFO - starting webserver on 'localhost:57174' 19:08:51 INFO - SUITE-START | Running 2 tests 19:08:51 INFO - TEST-START | dromaeo_css 19:08:51 INFO - operating with platform_type : linux_ 19:08:51 INFO - Initialising browser for dromaeo_css test... 19:08:51 INFO - TEST-INFO | started process 20879 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCkHYix/profile http://localhost:57174/getInfo.html) 19:08:55 INFO - PID 20879 | __metrics Screen width/height:1600/1200 19:08:55 INFO - PID 20879 | colorDepth:24 19:08:55 INFO - PID 20879 | Browser inner width/height: 1024/697 19:08:55 INFO - PID 20879 | __metrics 19:08:57 INFO - PID 20879 | 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" 19:08:57 INFO - PID 20879 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:08:58 INFO - TEST-INFO | 20879: exit 0 19:08:58 INFO - Browser initialized. 19:08:58 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:58 INFO - TEST-INFO | started process 20973 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCkHYix/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:10 INFO - PID 20973 | RSS: Main: 150204416 19:09:10 INFO - PID 20973 | 19:12:15 INFO - PID 20973 | Cycle 1(1): loaded http://localhost:57174/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57174/tests/dromaeo/cssquery-ext.html) 19:12:17 INFO - PID 20973 | RSS: Main: 208404480 19:12:17 INFO - PID 20973 | 19:14:00 INFO - PID 20973 | ************************* 19:14:00 INFO - PID 20973 | A coding exception was thrown and uncaught in a Task. 19:14:00 INFO - PID 20973 | 19:14:00 ERROR - PID 20973 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:14:00 INFO - PID 20973 | Full stack: 19:14:00 INFO - PID 20973 | ************************* 19:15:20 INFO - PID 20973 | Cycle 1(1): loaded http://localhost:57174/tests/dromaeo/cssquery-ext.html (next: http://localhost:57174/tests/dromaeo/cssquery-jquery.html) 19:15:21 INFO - PID 20973 | RSS: Main: 226177024 19:15:21 INFO - PID 20973 | 19:18:23 INFO - PID 20973 | Cycle 1(1): loaded http://localhost:57174/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57174/tests/dromaeo/cssquery-mootools.html) 19:18:23 INFO - PID 20973 | RSS: Main: 177405952 19:18:23 INFO - PID 20973 | 19:21:26 INFO - PID 20973 | Cycle 1(1): loaded http://localhost:57174/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57174/tests/dromaeo/cssquery-prototype.html) 19:21:26 INFO - PID 20973 | RSS: Main: 180125696 19:21:26 INFO - PID 20973 | 19:24:29 INFO - PID 20973 | Cycle 1(1): loaded http://localhost:57174/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57174/tests/dromaeo/cssquery-yui.html) 19:24:30 INFO - PID 20973 | RSS: Main: 188088320 19:24:30 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | Cycle 1(1): loaded http://localhost:57174/tests/dromaeo/cssquery-yui.html (next: http://localhost:57174/tests/dromaeo/cssquery-dojo.html) 19:27:33 INFO - PID 20973 | RSS: Main: 274644992 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | __start_tp_report 19:27:33 INFO - PID 20973 | _x_x_mozilla_page_load 19:27:33 INFO - PID 20973 | _x_x_mozilla_page_load_details 19:27:33 INFO - PID 20973 | |i|pagename|runs| 19:27:33 INFO - PID 20973 | |0;dojo.html;2624.960625590616;2651.098626467001;2676.6787985441742;2678.6919504257016;2684.288663504172;4935.679180853251;5009.9749501252445;5074.594032477397;5079.1365467870455;5103.464136265689;4966.031623833345;4993.800247990076;4994.076095922257;4997.2753950677225;5004.674696144748;4564.42944631921;4587.059652771174;4595.540445955395;4611.953880461203;4652.97673511632;4639.373684552593;4642.141203877293;4694.155052090622;4699.201135806904;4765.571098601125;4688.953110468903;4718.410198725142;4720.716756994591;4722.669413141081;4770.45139808923;575.8462509044377;4557.567031132037;4574.931376029363;4693.671442999009;4698.694584851974;4095;4564.2240819060835;4603.608693261077;4610.170169369512;4628.305754136906;4948.1093403187415;4955.90088198234;4993.425756038066;5013.147764879979;5041.546260836506;5345.251664766897;5396.78412863485;5445.455454454563;5454.836354909359;5460.290162278879;5364.919526207071;5433.320834895638;5435.592330575223;5448.891022179574;5453.15476101201;5031.396232452056;5055.646104772704;5060.038592667382;5060.569851562658;5096.898062038776;5578.386377498472;5581.274434323594;5585.776568937197;5586.832395028075;5632.915506267409;5136.717480538531;5206.557442617344;5208.921866171935;5260.579153667697;5302;5458.836234912959;5461.399246082928;5485.6434331768305;5511.944880551143;5519.365272993577;5213.7914483421;5308.416074231832;5308.840734777963;5308.92036619451;5309.787608495694;5233.633645644768;5312.9734351328;5332;5339.279197308314;5341.839744807584;4581;4587.495375508692;4589.586937175603;4589.724616523019;4615.676902616785;3978.801059947049;4004.799760011988;4036.7174297798874;4050.9392359114636;4075.1238483726142;4670.579647831712;4673.6728429009645;4678.812847486062;4685;4717.127331443694;5323;5396.163594642837;5397.136458166675;5440.619156659075;5510.504054635102;4701.553352431513;4703;4705.623550115983;4714.269288260325;4749.928751068736;4876.82931097417;4922.606191504671;4924.4583095859425;4930.457649658535;4961.950380496149;4964.702117872795;5023.8492845214705;5024.899502010013;5128.9487105129965;5164.251183578435;5468.562514998711;5487.725613719378;5503.7798488061635;5561.527270181918;5573.972130139486;5112.923306150485;5130.615203859651;5133.974330128325;5137.33214682089;5180.7409629519125;5219.530242278213;5280.287161233338;5409.486098820606;5412.052890744183;5413.837584872303;5437.564994800486;5487.957288115245;5522.0336441123445;5535.7785688572;5544.445555444423;5258;5288.471152884681;5304.840854774363;5305.098133317269;5315.4418786028455;5117.360329958755;5309.044372012921;5309.097453433003;5311.787528498817;5344.866378340417;5127.46161653036;5201.42784293735;5236.136037553761;5275.235090911719;5291.814786482455;5354.651947623392;5362.463753624606;5372.140457526777;5372.355317361941;5375.81184658535;5039.445660977216;5118.3090282811345;5119.846404607868;5139.845804625717;5224.955008998291 19:27:33 INFO - PID 20973 | |1;ext.html;727.6434547071934;745.3143108340324;1059.0256963593497;1067.5409573883228;1072.3887384191014;30456;31339.686603133963;31339.68660313399;31348.059558213292;31448.84275578619;33495.83252083742;33865;33866.8306658467;33870.49194262088;33895.49156762651;5636.887262254752;7175.210726820059;7274.090738657668;7284.162321333061;7288.635568221594;5651.2935883014625;7058.647067646622;7171.7489887853935;7174.139103307611;7198.316159964795;7267.164276108236;7304.3426091651745;7317.487775855692;7321.414286857039;7323.816904577401;5682.54539636828;7231.783046508614;7255.927440725631;7277.490575659706;7289.781306560838;5730.19777231188;7203.567785932808;7351.117865856131;7359.448041396917;7361.30067643573;31743.20641983946;32001.6799832001;32089.679103208902;32095.679043209737;32127.357452851047;2506.523760485502;2509.498100379913;2512.309114993373;2520.4959008198434;2548.5412625727363;22351.888240558856;22486.212982545698;22573.32280031585;24299.392515187086;24823.50352992948;2563.4360440703017;2575.626534152556;2606.27024433159;2618.6726659167603;2653.6815582130075;139759;140379;140678;140686;140752;32168;33052.1736956578;40596.594034059875;40616;40713;39632.603673963466;40638.59361406348;40671.59328406737;40750.31371392268;40878.79560602178;10993.175511836644;11086.556537738561;11087.944560277147;11100;11102.777944441066;9143.31425143117;9145.222656074307;9148.405353651991;9153.038930912158;9155.267578593697;13022.088453808143;13270.867291326964;13411.865881341257;13443.32783360828;13589.388477518338;5397.892042159135;5479.890402191933;5494.571411433056;5520;5520.558355331564;860.2086080805549;890.470170248701;904.213334399077;904.3059103562023;924.3021518753299;20632;21384.78615213859;21519;21553.568928621655;21992.230271940407;938.6808485115093;949.321235316738;957.2007373842919;963.7927845513249;978.7014960437135;10931.890681093246;11301.26541774782;11318.207725459304;11332.376719280519;11439.084873210124;226722;228490;228885;228979;229258;41430;42025.159496810506;42031.369529457086;42151.15697686029;42217.78891105525;1168.8750312042696;1235.9876401235872;1236.1470585296117;1250.1748845761754;1250.449802087097;19677.409677709693;20178.495537611678;20871.686924695838;20966.47583810417;21067.578648427254;31691.84154079215;31706.36587268288;31718.524222137148;31730.365392692478;31732.682673173895;211232;214730;215019;215407;215861;23621.29136125919;23845.284641460785;26205.986311574412;26659.7334026665;30106.096817095524;33793.324133517686;34062;34641.30717385588;34728;34850.47724284189;25836.483270334866;26023.869880650476;26299;26623.201303960912;26881.731182687923;25461;25649.230523083592;26013.479730405663;26061.178811240985;26538.469230615665;28756;28762.568561472013;28806;28841.7115828839;29049.564256535745;25639.743602563736;25718;25763.6135457972;26470;32401;25204.74795252024;27728.306792329546;28274.71725282803;28654.570181446878;28805.135845924655 19:27:33 INFO - PID 20973 | |2;jquery.html;401.77299825598544;407.14499550943026;416.6604217562688;422.6808759386662;427.2160585325927;11683.645190267769;17220.655586888253;17330.13349332533;17978;17992.820071799295;12826.80759788604;12867.613971580844;14960.775588366183;15061.849381506181;17074.914625426885;5509.669419834822;5520.447955204488;5616.466435688603;5617.297837770278;5648;5513;5583;5591.888162236753;5600;5605.131204663284;5384.300040994684;5586.776528938858;5609.635373700717;5623.156526521014;5630.831075067755;5650.5479561635175;5663.716814159276;5680.261565996394;5693.402192769782;5709.571782116358;4950.4307004694565;5091.643584949055;5143.537081662668;5145.073886700392;5145.434002259748;22209.22267720622;22301.107955681753;22354.66468002981;22395.44011399728;22618.208362707228;1548.9767653485208;1552.0454920224095;1569.6585793394672;1576.282791329942;1609.9195040247942;13046.934765326208;13084.345782710921;13439.86560134396;14401.567952961428;14973.475928342456;1685.0984723173167;1689.9408520701716;1720.1485264793882;1733.0901276829638;1739.9565010874703;83376;86719.13280867174;89578;90335;91676;10464.21518386124;10501.737456563647;10763.354198747944;10792.352458852494;10888.891111088946;14816.3332650031;14837.183954882585;14895.329710163069;14930.477433289783;14945.103293802189;6142.140100385949;6746.730130794714;7044.189918159374;7191.604461754549;7239.203687594466;5625.662460252398;5638.859028524238;5751.769929202868;5774.855628609234;5816.156657284755;6832.726690932307;6865.5537390069485;6866.553674011173;7071.257517960643;7072.3634872861685;1955.491572191212;1980.722698822166;1999.0309545098066;2010;2120.67129594913;830.7964548685611;847.4745657692271;855.5551113420986;869.0440515432973;882.6292956958093;16448.917755411385;16458.09480478585;17056.403025894033;17519.91240043817;17535.824641753676;855.4354126276628;859.6604341285157;861.1646702698498;866.9479831210148;869.452245085592;9570.377925434825;9571.521423928776;9584.856227156599;9682.467464289532;9781.266405019653;432825;451042;452679;453517;471955;80653.59673201597;88323.55838220767;93188;93419.53290233642;94306;1044.8328267477168;1046.6493724602192;1083.4691001409253;1089.3028461784463;1096.3915027160003;15380;15476.303566339768;15868.206589670239;16375.918120409322;16595.83404165943;42209.36685949775;42307.576924230365;42389.78805105955;42725.57274427216;43067.56932430761;52894;55259.723701381234;56030.159547606;56503.434965649816;57210.42789572051;7752.4573279869865;7873.133955265031;8104.351651867954;8206.876896846672;8277.875831862406;11505.942470287595;12226.327551984727;12411.131220814455;12499.56251531192;13020.046992951133;10334.741631459274;10517.9474102629;10592.735181620521;10607.151427885596;11004.339739615649;8735.519546424754;9271.443713377219;9322.161005509537;9593.952030240083;9674.9032509674;9809.362391444532;9849.389911673556;10097.343672661253;10139.442330671884;10149.188064954933;7523.8495230093995;7649.770506884803;7719.614019299125;7792.1428642848105;7813.335866451188;9417.62329506812;9908.405495670282;10277.845832312372;10289.588416463257;10739.624113156002 19:27:33 INFO - PID 20973 | |3;mootools.html;1260.6596219020864;1305.3277562055544;1306.9346532673367;1312.0815429199563;1326.3832317972142;9284.535773211346;9643.180329671986;9645.855312170313;9647.855282170756;9654.324197306192;9427.05729427057;9577.808443831136;9954.65158719445;10020;10031.548580313885;2171.3594489625557;2204.9228277010307;2209.259897934186;2211.447138215446;2220.156340590573;2066.2664754012335;2170.967435488469;2183.0285522942295;2220.4448887778058;2230.8438409311275;1834.385480863912;1892.933747318844;1907.3515004898331;1908.1794828223858;1910.0927059646626;2125.330520885916;2196.286206982737;2198.3075331270597;2201.8458707890454;2201.9889900550556;2120.342693764938;2144.3702785882683;2156.5255643758346;2171.250918433138;2175.912963481459;9804.215662746963;9917.404955702681;9929.950350248275;9936.453495057769;9940.701778946572;5040.747962601892;5205.24523944026;5211.7133557654315;5216.765245563921;5240.161574148118;3151.590293261884;3190.7287880530184;3264.379767844102;3266.8856590019473;3267.8725839585304;1038.3199004651965;1038.771470276538;1047.1204188481636;1051.4007016000883;1060.1095080132725;14956.326965286587;14991.775123373158;15068;15114.697706045818;15115.697686046327;4465.598096171295;4708.128996135726;4729.43246810385;4807.206810876166;4829.44461386945;10851.131909447384;10885;10914.072303854262;10921.290116142583;10953.83569246462;4973.42805577356;4977.552020318189;4981.800727970913;4985.0029994000615;4992.950070499322;4563.888444941686;4569.314602809539;4623.052274283764;4625.1443482955765;4651.046535460223;3519.2081781598936;3602.081469225383;3637.490751294768;3698.204885949534;3725.590185079639;1083.978320433587;1097.6158344579333;1105.8064838653365;1110.245033377295;1114.637742733615;601.437655791836;644.432899048834;662.0995446193174;673.8079647300594;685.7771224352106;5289.127293996447;5327.413984461706;5339.919901201485;5387.488188622075;5642.294713160855;812.34200297759;813.8209593889335;829.4484168028314;839.0895877972346;874.6763697431991;3024.9395012099635;3206.951895721566;3758.2671379080816;3854.8265328060306;3962.167944731581;12485.750284994248;12979.41592628334;12996.155249908727;13016.674583135497;13054.934725326502;13093.672658183621;13180.209187448783;13206.141600795918;13319.667008324675;13360.465581376831;9140.54297285133;9418.058194180665;9423.670171543963;9425.717228483156;9427.528623568795;5704.286964129484;5821.359650438533;5847.239858818326;5905.648924843521;6018.428249316308;11979.802019797951;12029.097817663886;12090.516379344728;12283.815742763687;12295.75408491843;11872.821907671565;12403.62789116328;12452.937735311265;12480.438380272728;12488.687782805502;8306;8623.698170564001;8631.654733810828;8632.741017769476;8634.222919937236;8780.209781119729;8862.113788621085;8909.331800114887;8969.071649595367;8998.59506322204;7522.097348318237;7565.508241964255;7588.582627955516;7626.237376262329;7844;5643.604947653789;7233.095863017123;7258.201597824341;7512.39900807945;7530.811729706801;6636.336366363325;6687.565308254948;6698.39714425704;6702.061711360511;6710.530262881552;4619.191641462798;4677.438707355139;4719.905601888012;4758.31004504338;4846.660733748745;4497.617702495194;4616.8614941551805;4674.462436819673;4740.478547359857;4906.607471402351 19:27:33 INFO - PID 20973 | |4;prototype.html;369.6470916445807;394.0857211269854;400.21158318113316;401.71478250442186;403.4573498644322;5310.123829568117;5384.219288203209;5406.729663516819;5412.161115027167;5417.074779619011;5033.54698077173;5193.584513238941;5227.320448341719;5258;5298.523132918037;4095.7133000689964;4127.62851343379;4151.335786274197;4156.667466602679;4158.604932531404;4145.543990161095;4146.543880173179;4158.792060396968;4159.376093585971;4163.958360416387;3993.7204395692315;4060.9593904061026;4125.989132662502;4128.752274863503;4137.937930931039;4081.163361510883;4095.0515628747403;4095.9795201024103;4098.581836716322;4112.506499220098;3583.94624080639;3596.4605309203566;3613.692836108934;3622.2031153146268;3625.419932810764;4996.200607902754;5167.509086636764;5168.8966220675375;5173.793048278064;5203;4717.433907931036;4727.463574338354;4736.905261894743;4752.904941901177;4752.928706069377;5540.944590554083;5569.777208911639;5594.2447769551;5601.6358936668985;5604.439556044363;3966.206758648253;3969.7816620085882;3974.880753577397;3975.463312452811;4003.5195776506716;5608.298962629671;5658.207850900877;5708.743106560215;5761.769529218826;5945.167676525247;4014.0767623446186;4045.4134150548593;4051.2505186540584;4052.209819085309;4060.939085913714;4136.503619565611;4144.088300573929;4144.48193975753;4145.253854306194;4146.217126635515;4449.933251001237;4452;4510.571495707903;4686.297055441642;4721.409823772028;5589.664620122806;5593.496585307342;5600.915986260209;5618.101103823368;5621.128725047624;1410.4428750643438;1419.7870319451958;1425.2232533269394;1427.8072460217945;1428.1502506008908;926.5645146781002;965.8309795785709;977.4917043137528;979.7501637082498;988.7725823060592;4914.680545764514;4919.450373132452;4921.335619691367;4933;4943.184374578154;5814.273215848019;5814.447627475383;5827.621204621686;5836.620619659709;5839.678817664984;4710.929336059962;4861.416630004422;4879.56083952446;4898.485659005824;4905.337779399735;3045.771567132474;3050.6491753448195;3069.5395690646137;3089.5211242257487;3094.9535756963664;5373.7581808818695;5383.246345511631;5413.648112872651;5422.213779002022;5426.480585436084;5668.858278543069;5886.23478947734;5899.410058994152;5910.615809972338;5921.555883308769;5350.464953504618;5379.435159308295;5388.784448622089;5426.457354264463;5438.564914806727;5530.446955304598;5755.71221438918;5793.884122317446;5844.516080665615;5861.794837180678;5572.944270557243;5595.804146854841;5612.803551875665;5678.4605462480995;5682.971585142048;5127.128388173945;5278.736063196901;5296.781740199699;5304.010995876546;5328.60035497347;3739.6447337502896;3881.4177873319227;3885.7085718570656;4076.1643863008603;4110.630043296118;5817.331006934257;5841.620294680833;5852.94147058524;5862.618929769551;5866.911996319973;5462.508374246337;5625.943740562542;5662.915056274242;5672.347680016851;5682.499700131929;5621.831345059655;5623.915641265467;5634.211210430461;5651.6043876928225;5667.234923285304;5340.225667278145;5519.2824932758485;5560.304961879765;5565.831175453201;5572.136318870582;5590.720463976703;5634.6337488063145;5644.887102258014;5653.802116925888;5659.122835960433;5521.723913804213;5529.419410961791;5530.778768849201;5531.585131115102;5537.944620553743 19:27:33 INFO - PID 20973 | |5;yui.html;580.9273840769903;581.3314688108676;585.0843430032003;591.8875413671402;592.5082181789113;10512.947435262831;11096.722581935446;11107.72230694233;11299.322040677565;12079.698007549816;11745.530178792838;11869.7626047479;11900;11992.520299188021;12013.939930300336;829.2122483640535;834.6911642692212;835.1231207232412;835.7075023741703;835.9373047021468;812.6261919517029;840.260570697785;848.2874385516166;848.5205858689851;849.133883438895;888.5779254853925;890.132121181849;890.2655309369763;890.5725251879102;890.6971629645919;881.0220655072864;882.4176043811054;883.6465413834453;885.008790155024;886.0253720907015;654.8461111638761;657.4773055420952;657.8585604095142;657.8881590129641;659.5943494750742;12072.154949153562;12523.499060037588;12524.624261272176;12642.431090600852;12658.050646201571;12359.32023738694;12394.442250098768;12447.502099915993;12480.375981200994;12660.240385576895;9122.954385228099;9139.543022848831;9299.674511392137;9328.580213890391;9363.531823408803;2544.224011676438;2572.935676608081;2611.595202743578;2633.749793769608;2683.087750164934;64949;65400.672996635185;65525;66853.33146668569;66964;8910.599023043978;8931.240844528158;9089.590968406437;9707.9029209707;9844.310898237054;17851.73222401665;18208.45374638763;18217.18022688982;18245.817541824414;18248.45254642363;6192.783252586228;6273.0904018917;6296.874062518816;6519;6586.1438013058;3146.24490122369;3220.4686226772787;3254;3298.86804527821;3413.992872102736;3259.1200375898375;3651.835667394974;3802.049487628093;3839.155385815116;3845.6346647068485;720.330093013492;724.9637518124073;727.4253339861556;753.9509931854412;756.5347311403448;475;475.9809607615726;479.7313504437526;483.15447966059395;486.79068000759304;7954.032342097871;8187;8241.711540096068;8413.681819992775;8507.191816777397;458.98164073436686;474.962003039756;476.41401076675885;483.13518801345197;488.47595502033175;3702.162237152429;3756.1173124315765;3843.2889915365745;3906.3945088511327;3907.843686252518;61847;63028;63637;65659;65923;41428.58571414247;44478.55521444809;55172.72413637987;56312;56340.71829640825;46082.76958615185;46302.305465418045;47043.29435058546;47781.283280751515;48816.51183488191;7354.264573542603;7622.885656715042;7785.532868027936;7845.3723702104835;7899.723509677134;27289.86355068212;27295.044673436336;27747.58378624282;27965.30086747766;28332.858335708188;26668.59997100006;32490.51264230991;32598.34803303968;32856.178595535304;33049.669503304656;9488;9514.809703806024;9526.714198574055;9561.856572151562;9564.760880977752;9713.258801803122;9842.261830362895;9986.051325124101;10181.592736290466;10318.226133039901;9152;9379.812403752023;9416;9798;9874.40753554789;9699.6120155193;9914.355566888129;9975.750606234611;10040;10430.217733669853;9898.158656514277;9926.454045027593;9959.502024898871;9976.900230997597;10155.441450720282;7093.929060709327;7212.2427145151105;7355.1541572719825;7373;7382.704691812267;7616.543007419573;7719.807004824925;7828.373730101692;7979.48133371329;8123.47197432165 19:27:33 INFO - PID 20973 | __end_tp_report 19:27:33 INFO - PID 20973 | __start_cc_report 19:27:33 INFO - PID 20973 | _x_x_mozilla_cycle_collect,1762 19:27:33 INFO - PID 20973 | __end_cc_report 19:27:33 INFO - PID 20973 | __startTimestamp1490322453786__endTimestamp 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | ------- Summary: start ------- 19:27:33 INFO - PID 20973 | Number of tests: 6 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | [#0] dojo.html Cycles:165 Average:4971.53 Median:5127.46 stddev:649.26 (12.7%) stddev-sans-first:624.61 19:27:33 INFO - PID 20973 | Values: 2625.0 2651.1 2676.7 2678.7 2684.3 4935.7 5010.0 5074.6 5079.1 5103.5 4966.0 4993.8 4994.1 4997.3 5004.7 4564.4 4587.1 4595.5 4612.0 4653.0 4639.4 4642.1 4694.2 4699.2 4765.6 4689.0 4718.4 4720.7 4722.7 4770.5 575.8 4557.6 4574.9 4693.7 4698.7 4095.0 4564.2 4603.6 4610.2 4628.3 4948.1 4955.9 4993.4 5013.1 5041.5 5345.3 5396.8 5445.5 5454.8 5460.3 5364.9 5433.3 5435.6 5448.9 5453.2 5031.4 5055.6 5060.0 5060.6 5096.9 5578.4 5581.3 5585.8 5586.8 5632.9 5136.7 5206.6 5208.9 5260.6 5302.0 5458.8 5461.4 5485.6 5511.9 5519.4 5213.8 5308.4 5308.8 5308.9 5309.8 5233.6 5313.0 5332.0 5339.3 5341.8 4581.0 4587.5 4589.6 4589.7 4615.7 3978.8 4004.8 4036.7 4050.9 4075.1 4670.6 4673.7 4678.8 4685.0 4717.1 5323.0 5396.2 5397.1 5440.6 5510.5 4701.6 4703.0 4705.6 4714.3 4749.9 4876.8 4922.6 4924.5 4930.5 4962.0 4964.7 5023.8 5024.9 5128.9 5164.3 5468.6 5487.7 5503.8 5561.5 5574.0 5112.9 5130.6 5134.0 5137.3 5180.7 5219.5 5280.3 5409.5 5412.1 5413.8 5437.6 5488.0 5522.0 5535.8 5544.4 5258.0 5288.5 5304.8 5305.1 5315.4 5117.4 5309.0 5309.1 5311.8 5344.9 5127.5 5201.4 5236.1 5275.2 5291.8 5354.7 5362.5 5372.1 5372.4 5375.8 5039.4 5118.3 5119.8 5139.8 5225.0 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | [#1] ext.html Cycles:180 Average:33008.93 Median:29577.83 stddev:51642.03 (174.6%) stddev-sans-first:51730.02 19:27:33 INFO - PID 20973 | Values: 727.6 745.3 1059.0 1067.5 1072.4 30456.0 31339.7 31339.7 31348.1 31448.8 33495.8 33865.0 33866.8 33870.5 33895.5 5636.9 7175.2 7274.1 7284.2 7288.6 5651.3 7058.6 7171.7 7174.1 7198.3 7267.2 7304.3 7317.5 7321.4 7323.8 5682.5 7231.8 7255.9 7277.5 7289.8 5730.2 7203.6 7351.1 7359.4 7361.3 31743.2 32001.7 32089.7 32095.7 32127.4 2506.5 2509.5 2512.3 2520.5 2548.5 22351.9 22486.2 22573.3 24299.4 24823.5 2563.4 2575.6 2606.3 2618.7 2653.7 139759.0 140379.0 140678.0 140686.0 140752.0 32168.0 33052.2 40596.6 40616.0 40713.0 39632.6 40638.6 40671.6 40750.3 40878.8 10993.2 11086.6 11087.9 11100.0 11102.8 9143.3 9145.2 9148.4 9153.0 9155.3 13022.1 13270.9 13411.9 13443.3 13589.4 5397.9 5479.9 5494.6 5520.0 5520.6 860.2 890.5 904.2 904.3 924.3 20632.0 21384.8 21519.0 21553.6 21992.2 938.7 949.3 957.2 963.8 978.7 10931.9 11301.3 11318.2 11332.4 11439.1 226722.0 228490.0 228885.0 228979.0 229258.0 41430.0 42025.2 42031.4 42151.2 42217.8 1168.9 1236.0 1236.1 1250.2 1250.4 19677.4 20178.5 20871.7 20966.5 21067.6 31691.8 31706.4 31718.5 31730.4 31732.7 211232.0 214730.0 215019.0 215407.0 215861.0 23621.3 23845.3 26206.0 26659.7 30106.1 33793.3 34062.0 34641.3 34728.0 34850.5 25836.5 26023.9 26299.0 26623.2 26881.7 25461.0 25649.2 26013.5 26061.2 26538.5 28756.0 28762.6 28806.0 28841.7 29049.6 25639.7 25718.0 25763.6 26470.0 32401.0 25204.7 27728.3 28274.7 28654.6 28805.1 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | [#2] jquery.html Cycles:180 Average:27439.89 Median:238452.72 stddev:75056.27 (31.5%) stddev-sans-first:75239.37 19:27:33 INFO - PID 20973 | Values: 401.8 407.1 416.7 422.7 427.2 11683.6 17220.7 17330.1 17978.0 17992.8 12826.8 12867.6 14960.8 15061.8 17074.9 5509.7 5520.4 5616.5 5617.3 5648.0 5513.0 5583.0 5591.9 5600.0 5605.1 5384.3 5586.8 5609.6 5623.2 5630.8 5650.5 5663.7 5680.3 5693.4 5709.6 4950.4 5091.6 5143.5 5145.1 5145.4 22209.2 22301.1 22354.7 22395.4 22618.2 1549.0 1552.0 1569.7 1576.3 1609.9 13046.9 13084.3 13439.9 14401.6 14973.5 1685.1 1689.9 1720.1 1733.1 1740.0 83376.0 86719.1 89578.0 90335.0 91676.0 10464.2 10501.7 10763.4 10792.4 10888.9 14816.3 14837.2 14895.3 14930.5 14945.1 6142.1 6746.7 7044.2 7191.6 7239.2 5625.7 5638.9 5751.8 5774.9 5816.2 6832.7 6865.6 6866.6 7071.3 7072.4 1955.5 1980.7 1999.0 2010.0 2120.7 830.8 847.5 855.6 869.0 882.6 16448.9 16458.1 17056.4 17519.9 17535.8 855.4 859.7 861.2 866.9 869.5 9570.4 9571.5 9584.9 9682.5 9781.3 432825.0 451042.0 452679.0 453517.0 471955.0 80653.6 88323.6 93188.0 93419.5 94306.0 1044.8 1046.6 1083.5 1089.3 1096.4 15380.0 15476.3 15868.2 16375.9 16595.8 42209.4 42307.6 42389.8 42725.6 43067.6 52894.0 55259.7 56030.2 56503.4 57210.4 7752.5 7873.1 8104.4 8206.9 8277.9 11505.9 12226.3 12411.1 12499.6 13020.0 10334.7 10517.9 10592.7 10607.2 11004.3 8735.5 9271.4 9322.2 9594.0 9674.9 9809.4 9849.4 10097.3 10139.4 10149.2 7523.8 7649.8 7719.6 7792.1 7813.3 9417.6 9908.4 10277.8 10289.6 10739.6 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | [#3] mootools.html Cycles:180 Average:6115.56 Median:4593.09 stddev:4048.46 (88.1%) stddev-sans-first:4043.39 19:27:33 INFO - PID 20973 | Values: 1260.7 1305.3 1306.9 1312.1 1326.4 9284.5 9643.2 9645.9 9647.9 9654.3 9427.1 9577.8 9954.7 10020.0 10031.5 2171.4 2204.9 2209.3 2211.4 2220.2 2066.3 2171.0 2183.0 2220.4 2230.8 1834.4 1892.9 1907.4 1908.2 1910.1 2125.3 2196.3 2198.3 2201.8 2202.0 2120.3 2144.4 2156.5 2171.3 2175.9 9804.2 9917.4 9930.0 9936.5 9940.7 5040.7 5205.2 5211.7 5216.8 5240.2 3151.6 3190.7 3264.4 3266.9 3267.9 1038.3 1038.8 1047.1 1051.4 1060.1 14956.3 14991.8 15068.0 15114.7 15115.7 4465.6 4708.1 4729.4 4807.2 4829.4 10851.1 10885.0 10914.1 10921.3 10953.8 4973.4 4977.6 4981.8 4985.0 4993.0 4563.9 4569.3 4623.1 4625.1 4651.0 3519.2 3602.1 3637.5 3698.2 3725.6 1084.0 1097.6 1105.8 1110.2 1114.6 601.4 644.4 662.1 673.8 685.8 5289.1 5327.4 5339.9 5387.5 5642.3 812.3 813.8 829.4 839.1 874.7 3024.9 3207.0 3758.3 3854.8 3962.2 12485.8 12979.4 12996.2 13016.7 13054.9 13093.7 13180.2 13206.1 13319.7 13360.5 9140.5 9418.1 9423.7 9425.7 9427.5 5704.3 5821.4 5847.2 5905.6 6018.4 11979.8 12029.1 12090.5 12283.8 12295.8 11872.8 12403.6 12452.9 12480.4 12488.7 8306.0 8623.7 8631.7 8632.7 8634.2 8780.2 8862.1 8909.3 8969.1 8998.6 7522.1 7565.5 7588.6 7626.2 7844.0 5643.6 7233.1 7258.2 7512.4 7530.8 6636.3 6687.6 6698.4 6702.1 6710.5 4619.2 4677.4 4719.9 4758.3 4846.7 4497.6 4616.9 4674.5 4740.5 4906.6 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | [#4] prototype.html Cycles:180 Average:4630.66 Median:5287.76 stddev:1348.00 (25.5%) stddev-sans-first:1313.29 19:27:33 INFO - PID 20973 | Values: 369.6 394.1 400.2 401.7 403.5 5310.1 5384.2 5406.7 5412.2 5417.1 5033.5 5193.6 5227.3 5258.0 5298.5 4095.7 4127.6 4151.3 4156.7 4158.6 4145.5 4146.5 4158.8 4159.4 4164.0 3993.7 4061.0 4126.0 4128.8 4137.9 4081.2 4095.1 4096.0 4098.6 4112.5 3583.9 3596.5 3613.7 3622.2 3625.4 4996.2 5167.5 5168.9 5173.8 5203.0 4717.4 4727.5 4736.9 4752.9 4752.9 5540.9 5569.8 5594.2 5601.6 5604.4 3966.2 3969.8 3974.9 3975.5 4003.5 5608.3 5658.2 5708.7 5761.8 5945.2 4014.1 4045.4 4051.3 4052.2 4060.9 4136.5 4144.1 4144.5 4145.3 4146.2 4449.9 4452.0 4510.6 4686.3 4721.4 5589.7 5593.5 5600.9 5618.1 5621.1 1410.4 1419.8 1425.2 1427.8 1428.2 926.6 965.8 977.5 979.8 988.8 4914.7 4919.5 4921.3 4933.0 4943.2 5814.3 5814.4 5827.6 5836.6 5839.7 4710.9 4861.4 4879.6 4898.5 4905.3 3045.8 3050.6 3069.5 3089.5 3095.0 5373.8 5383.2 5413.6 5422.2 5426.5 5668.9 5886.2 5899.4 5910.6 5921.6 5350.5 5379.4 5388.8 5426.5 5438.6 5530.4 5755.7 5793.9 5844.5 5861.8 5572.9 5595.8 5612.8 5678.5 5683.0 5127.1 5278.7 5296.8 5304.0 5328.6 3739.6 3881.4 3885.7 4076.2 4110.6 5817.3 5841.6 5852.9 5862.6 5866.9 5462.5 5625.9 5662.9 5672.3 5682.5 5621.8 5623.9 5634.2 5651.6 5667.2 5340.2 5519.3 5560.3 5565.8 5572.1 5590.7 5634.6 5644.9 5653.8 5659.1 5521.7 5529.4 5530.8 5531.6 5537.9 19:27:33 INFO - PID 20973 | 19:27:33 INFO - PID 20973 | [#5] yui.html Cycles:180 Average:13284.46 Median:64293.00 stddev:17213.59 (26.8%) stddev-sans-first:17235.45 19:27:33 INFO - PID 20973 | Values: 580.9 581.3 585.1 591.9 592.5 10512.9 11096.7 11107.7 11299.3 12079.7 11745.5 11869.8 11900.0 11992.5 12013.9 829.2 834.7 835.1 835.7 835.9 812.6 840.3 848.3 848.5 849.1 888.6 890.1 890.3 890.6 890.7 881.0 882.4 883.6 885.0 886.0 654.8 657.5 657.9 657.9 659.6 12072.2 12523.5 12524.6 12642.4 12658.1 12359.3 12394.4 12447.5 12480.4 12660.2 9123.0 9139.5 9299.7 9328.6 9363.5 2544.2 2572.9 2611.6 2633.7 2683.1 64949.0 65400.7 65525.0 66853.3 66964.0 8910.6 8931.2 9089.6 9707.9 9844.3 17851.7 18208.5 18217.2 18245.8 18248.5 6192.8 6273.1 6296.9 6519.0 6586.1 3146.2 3220.5 3254.0 3298.9 3414.0 3259.1 3651.8 3802.0 3839.2 3845.6 720.3 725.0 727.4 754.0 756.5 475.0 476.0 479.7 483.2 486.8 7954.0 8187.0 8241.7 8413.7 8507.2 459.0 475.0 476.4 483.1 488.5 3702.2 3756.1 3843.3 3906.4 3907.8 61847.0 63028.0 63637.0 65659.0 65923.0 41428.6 44478.6 55172.7 56312.0 56340.7 46082.8 46302.3 47043.3 47781.3 48816.5 7354.3 7622.9 7785.5 7845.4 7899.7 27289.9 27295.0 27747.6 27965.3 28332.9 26668.6 32490.5 32598.3 32856.2 33049.7 9488.0 9514.8 9526.7 9561.9 9564.8 9713.3 9842.3 9986.1 10181.6 10318.2 9152.0 9379.8 9416.0 9798.0 9874.4 9699.6 9914.4 9975.8 10040.0 10430.2 9898.2 9926.5 9959.5 9976.9 10155.4 7093.9 7212.2 7355.2 7373.0 7382.7 7616.5 7719.8 7828.4 7979.5 8123.5 19:27:33 INFO - PID 20973 | -------- Summary: end -------- 19:27:33 INFO - PID 20973 | 19:27:34 INFO - TEST-INFO | 20973: exit 0 19:27:34 INFO - TEST-OK | dromaeo_css | took 1122983ms 19:27:34 INFO - TEST-START | kraken 19:27:34 INFO - operating with platform_type : linux_ 19:27:34 INFO - Initialising browser for kraken test... 19:27:34 INFO - TEST-INFO | started process 21300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9IiqHz/profile http://localhost:57174/getInfo.html) 19:27:38 INFO - PID 21300 | __metrics Screen width/height:1600/1200 19:27:38 INFO - PID 21300 | colorDepth:24 19:27:38 INFO - PID 21300 | Browser inner width/height: 1024/697 19:27:38 INFO - PID 21300 | __metrics 19:27:40 INFO - PID 21300 | 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" 19:27:40 INFO - PID 21300 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:27:41 INFO - TEST-INFO | 21300: exit 0 19:27:41 INFO - Browser initialized. 19:27:41 INFO - Running cycle 1/1 for kraken test... 19:27:41 INFO - TEST-INFO | started process 21391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9IiqHz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:53 INFO - PID 21391 | RSS: Main: 150843392 19:27:53 INFO - PID 21391 | 19:27:56 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57174/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:57 INFO - PID 21391 | RSS: Main: 205590528 19:27:57 INFO - PID 21391 | 19:27:59 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57174/tests/kraken/driver.html?testName=audio-dft) 19:28:00 INFO - PID 21391 | RSS: Main: 184430592 19:28:00 INFO - PID 21391 | 19:28:03 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57174/tests/kraken/driver.html?testName=audio-fft) 19:28:04 INFO - PID 21391 | RSS: Main: 267313152 19:28:04 INFO - PID 21391 | 19:28:06 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57174/tests/kraken/driver.html?testName=audio-oscillator) 19:28:06 INFO - PID 21391 | RSS: Main: 178241536 19:28:06 INFO - PID 21391 | 19:28:09 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57174/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:09 INFO - PID 21391 | RSS: Main: 181030912 19:28:09 INFO - PID 21391 | 19:28:12 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57174/tests/kraken/driver.html?testName=imaging-darkroom) 19:28:13 INFO - PID 21391 | RSS: Main: 220332032 19:28:13 INFO - PID 21391 | 19:28:17 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57174/tests/kraken/driver.html?testName=imaging-desaturate) 19:28:17 INFO - PID 21391 | RSS: Main: 215752704 19:28:17 INFO - PID 21391 | 19:28:20 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57174/tests/kraken/driver.html?testName=json-parse-financial) 19:28:21 INFO - PID 21391 | RSS: Main: 217374720 19:28:21 INFO - PID 21391 | 19:28:22 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57174/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:23 INFO - PID 21391 | RSS: Main: 180834304 19:28:23 INFO - PID 21391 | 19:28:25 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:26 INFO - PID 21391 | RSS: Main: 188534784 19:28:26 INFO - PID 21391 | 19:28:28 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:28:28 INFO - PID 21391 | RSS: Main: 197984256 19:28:28 INFO - PID 21391 | 19:28:31 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:28:32 INFO - PID 21391 | RSS: Main: 201039872 19:28:32 INFO - PID 21391 | 19:28:35 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:28:36 INFO - PID 21391 | RSS: Main: 203374592 19:28:36 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | Cycle 1(1): loaded http://localhost:57174/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57174/tests/kraken/driver.html?testName=ai-astar) 19:28:38 INFO - PID 21391 | RSS: Main: 272338944 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | __start_tp_report 19:28:38 INFO - PID 21391 | _x_x_mozilla_page_load 19:28:38 INFO - PID 21391 | _x_x_mozilla_page_load_details 19:28:38 INFO - PID 21391 | |i|pagename|runs| 19:28:38 INFO - PID 21391 | |0;ai-astar;102;111;110;110;110;111;112;111;110;111 19:28:38 INFO - PID 21391 | |1;audio-beat-detection;147;123;122;177;126;122;128;122;151;122 19:28:38 INFO - PID 21391 | |2;audio-dft;163;163;164;162;166;164;163;162;163;161 19:28:38 INFO - PID 21391 | |3;audio-fft;74;74;74;111;75;74;77;75;93;74 19:28:38 INFO - PID 21391 | |4;audio-oscillator;85;83;112;84;83;84;112;84;83;108 19:28:38 INFO - PID 21391 | |5;imaging-gaussian-blur;113;114;114;113;114;113;113;113;113;113 19:28:38 INFO - PID 21391 | |6;imaging-darkroom;149;148;149;149;148;149;148;148;148;148 19:28:38 INFO - PID 21391 | |7;imaging-desaturate;94;95;95;95;94;94;95;94;93;94 19:28:38 INFO - PID 21391 | |8;json-parse-financial;64;72;62;62;62;64;65;64;63;65 19:28:38 INFO - PID 21391 | |9;json-stringify-tinderbox;55;55;54;55;55;55;55;55;56;55 19:28:38 INFO - PID 21391 | |10;stanford-crypto-aes;83;83;82;84;83;82;91;89;81;82 19:28:38 INFO - PID 21391 | |11;stanford-crypto-ccm;140;145;143;146;131;133;148;131;230;134 19:28:38 INFO - PID 21391 | |12;stanford-crypto-pbkdf2;189;195;197;199;201;205;199;197;194;195 19:28:38 INFO - PID 21391 | |13;stanford-crypto-sha256-iterative;72;71;70;72;73;73;71;72;71;74 19:28:38 INFO - PID 21391 | __end_tp_report 19:28:38 INFO - PID 21391 | __start_cc_report 19:28:38 INFO - PID 21391 | _x_x_mozilla_cycle_collect,2056 19:28:38 INFO - PID 21391 | __end_cc_report 19:28:38 INFO - PID 21391 | __startTimestamp1490322518476__endTimestamp 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | ------- Summary: start ------- 19:28:38 INFO - PID 21391 | Number of tests: 14 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#0] ai-astar Cycles:10 Average:109.80 Median:111.00 stddev:2.82 (2.5%) stddev-sans-first:0.71 19:28:38 INFO - PID 21391 | Values: 102.0 111.0 110.0 110.0 110.0 111.0 112.0 111.0 110.0 111.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:127.00 stddev:18.57 (14.6%) stddev-sans-first:19.09 19:28:38 INFO - PID 21391 | Values: 147.0 123.0 122.0 177.0 126.0 122.0 128.0 122.0 151.0 122.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.37 (0.8%) stddev-sans-first:1.45 19:28:38 INFO - PID 21391 | Values: 163.0 163.0 164.0 162.0 166.0 164.0 163.0 162.0 163.0 161.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#3] audio-fft Cycles:10 Average:80.10 Median:74.50 stddev:12.33 (16.6%) stddev-sans-first:12.88 19:28:38 INFO - PID 21391 | Values: 74.0 74.0 74.0 111.0 75.0 74.0 77.0 75.0 93.0 74.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:83.50 stddev:13.08 (15.7%) stddev-sans-first:13.64 19:28:38 INFO - PID 21391 | Values: 85.0 83.0 112.0 84.0 83.0 84.0 112.0 84.0 83.0 108.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 19:28:38 INFO - PID 21391 | Values: 113.0 114.0 114.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.52 (0.3%) stddev-sans-first:0.50 19:28:38 INFO - PID 21391 | Values: 149.0 148.0 149.0 149.0 148.0 149.0 148.0 148.0 148.0 148.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 19:28:38 INFO - PID 21391 | Values: 94.0 95.0 95.0 95.0 94.0 94.0 95.0 94.0 93.0 94.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:2.95 (4.6%) stddev-sans-first:3.12 19:28:38 INFO - PID 21391 | Values: 64.0 72.0 62.0 62.0 62.0 64.0 65.0 64.0 63.0 65.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#9] json-stringify-tinderbox Cycles:10 Average:55.00 Median:55.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 19:28:38 INFO - PID 21391 | Values: 55.0 55.0 54.0 55.0 55.0 55.0 55.0 55.0 56.0 55.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.00 stddev:3.30 (4.0%) stddev-sans-first:3.48 19:28:38 INFO - PID 21391 | Values: 83.0 83.0 82.0 84.0 83.0 82.0 91.0 89.0 81.0 82.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:144.00 stddev:29.49 (20.5%) stddev-sans-first:31.13 19:28:38 INFO - PID 21391 | Values: 140.0 145.0 143.0 146.0 131.0 133.0 148.0 131.0 230.0 134.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.10 Median:198.00 stddev:4.33 (2.2%) stddev-sans-first:3.46 19:28:38 INFO - PID 21391 | Values: 189.0 195.0 197.0 199.0 201.0 205.0 199.0 197.0 194.0 195.0 19:28:38 INFO - PID 21391 | 19:28:38 INFO - PID 21391 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:72.00 stddev:1.20 (1.7%) stddev-sans-first:1.27 19:28:38 INFO - PID 21391 | Values: 72.0 71.0 70.0 72.0 73.0 73.0 71.0 72.0 71.0 74.0 19:28:38 INFO - PID 21391 | -------- Summary: end -------- 19:28:38 INFO - PID 21391 | 19:28:39 INFO - TEST-INFO | 21391: exit 0 19:28:39 INFO - TEST-OK | kraken | took 64855ms 19:28:39 INFO - SUITE-END | took 1187s 19:28:39 INFO - Completed test suite (00:19:47) 19:28:39 INFO - javascript benchmark 19:28:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6397.37040951939, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2624.960625590616, 2651.098626467001, 2676.6787985441742, 2678.6919504257016, 2684.288663504172, 4935.679180853251, 5009.9749501252445, 5074.594032477397, 5079.1365467870455, 5103.464136265689, 4966.031623833345, 4993.800247990076, 4994.076095922257, 4997.2753950677225, 5004.674696144748, 4564.42944631921, 4587.059652771174, 4595.540445955395, 4611.953880461203, 4652.97673511632, 4639.373684552593, 4642.141203877293, 4694.155052090622, 4699.201135806904, 4765.571098601125, 4688.953110468903, 4718.410198725142, 4720.716756994591, 4722.669413141081, 4770.45139808923, 575.8462509044377, 4557.567031132037, 4574.931376029363, 4693.671442999009, 4698.694584851974, 4095.0, 4564.2240819060835, 4603.608693261077, 4610.170169369512, 4628.305754136906, 4948.1093403187415, 4955.90088198234, 4993.425756038066, 5013.147764879979, 5041.546260836506, 5345.251664766897, 5396.78412863485, 5445.455454454563, 5454.836354909359, 5460.290162278879, 5364.919526207071, 5433.320834895638, 5435.592330575223, 5448.891022179574, 5453.15476101201, 5031.396232452056, 5055.646104772704, 5060.038592667382, 5060.569851562658, 5096.898062038776, 5578.386377498472, 5581.274434323594, 5585.776568937197, 5586.832395028075, 5632.915506267409, 5136.717480538531, 5206.557442617344, 5208.921866171935, 5260.579153667697, 5302.0, 5458.836234912959, 5461.399246082928, 5485.6434331768305, 5511.944880551143, 5519.365272993577, 5213.7914483421, 5308.416074231832, 5308.840734777963, 5308.92036619451, 5309.787608495694, 5233.633645644768, 5312.9734351328, 5332.0, 5339.279197308314, 5341.839744807584, 4581.0, 4587.495375508692, 4589.586937175603, 4589.724616523019, 4615.676902616785, 3978.801059947049, 4004.799760011988, 4036.7174297798874, 4050.9392359114636, 4075.1238483726142, 4670.579647831712, 4673.6728429009645, 4678.812847486062, 4685.0, 4717.127331443694, 5323.0, 5396.163594642837, 5397.136458166675, 5440.619156659075, 5510.504054635102, 4701.553352431513, 4703.0, 4705.623550115983, 4714.269288260325, 4749.928751068736, 4876.82931097417, 4922.606191504671, 4924.4583095859425, 4930.457649658535, 4961.950380496149, 4964.702117872795, 5023.8492845214705, 5024.899502010013, 5128.9487105129965, 5164.251183578435, 5468.562514998711, 5487.725613719378, 5503.7798488061635, 5561.527270181918, 5573.972130139486, 5112.923306150485, 5130.615203859651, 5133.974330128325, 5137.33214682089, 5180.7409629519125, 5219.530242278213, 5280.287161233338, 5409.486098820606, 5412.052890744183, 5413.837584872303, 5437.564994800486, 5487.957288115245, 5522.0336441123445, 5535.7785688572, 5544.445555444423, 5258.0, 5288.471152884681, 5304.840854774363, 5305.098133317269, 5315.4418786028455, 5117.360329958755, 5309.044372012921, 5309.097453433003, 5311.787528498817, 5344.866378340417, 5127.46161653036, 5201.42784293735, 5236.136037553761, 5275.235090911719, 5291.814786482455, 5354.651947623392, 5362.463753624606, 5372.140457526777, 5372.355317361941, 5375.81184658535, 5039.445660977216, 5118.3090282811345, 5119.846404607868, 5139.845804625717, 5224.955008998291], "value": 4929.2560458511825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.6434547071934, 745.3143108340324, 1059.0256963593497, 1067.5409573883228, 1072.3887384191014, 30456.0, 31339.686603133963, 31339.68660313399, 31348.059558213292, 31448.84275578619, 33495.83252083742, 33865.0, 33866.8306658467, 33870.49194262088, 33895.49156762651, 5636.887262254752, 7175.210726820059, 7274.090738657668, 7284.162321333061, 7288.635568221594, 5651.2935883014625, 7058.647067646622, 7171.7489887853935, 7174.139103307611, 7198.316159964795, 7267.164276108236, 7304.3426091651745, 7317.487775855692, 7321.414286857039, 7323.816904577401, 5682.54539636828, 7231.783046508614, 7255.927440725631, 7277.490575659706, 7289.781306560838, 5730.19777231188, 7203.567785932808, 7351.117865856131, 7359.448041396917, 7361.30067643573, 31743.20641983946, 32001.6799832001, 32089.679103208902, 32095.679043209737, 32127.357452851047, 2506.523760485502, 2509.498100379913, 2512.309114993373, 2520.4959008198434, 2548.5412625727363, 22351.888240558856, 22486.212982545698, 22573.32280031585, 24299.392515187086, 24823.50352992948, 2563.4360440703017, 2575.626534152556, 2606.27024433159, 2618.6726659167603, 2653.6815582130075, 139759.0, 140379.0, 140678.0, 140686.0, 140752.0, 32168.0, 33052.1736956578, 40596.594034059875, 40616.0, 40713.0, 39632.603673963466, 40638.59361406348, 40671.59328406737, 40750.31371392268, 40878.79560602178, 10993.175511836644, 11086.556537738561, 11087.944560277147, 11100.0, 11102.777944441066, 9143.31425143117, 9145.222656074307, 9148.405353651991, 9153.038930912158, 9155.267578593697, 13022.088453808143, 13270.867291326964, 13411.865881341257, 13443.32783360828, 13589.388477518338, 5397.892042159135, 5479.890402191933, 5494.571411433056, 5520.0, 5520.558355331564, 860.2086080805549, 890.470170248701, 904.213334399077, 904.3059103562023, 924.3021518753299, 20632.0, 21384.78615213859, 21519.0, 21553.568928621655, 21992.230271940407, 938.6808485115093, 949.321235316738, 957.2007373842919, 963.7927845513249, 978.7014960437135, 10931.890681093246, 11301.26541774782, 11318.207725459304, 11332.376719280519, 11439.084873210124, 226722.0, 228490.0, 228885.0, 228979.0, 229258.0, 41430.0, 42025.159496810506, 42031.369529457086, 42151.15697686029, 42217.78891105525, 1168.8750312042696, 1235.9876401235872, 1236.1470585296117, 1250.1748845761754, 1250.449802087097, 19677.409677709693, 20178.495537611678, 20871.686924695838, 20966.47583810417, 21067.578648427254, 31691.84154079215, 31706.36587268288, 31718.524222137148, 31730.365392692478, 31732.682673173895, 211232.0, 214730.0, 215019.0, 215407.0, 215861.0, 23621.29136125919, 23845.284641460785, 26205.986311574412, 26659.7334026665, 30106.096817095524, 33793.324133517686, 34062.0, 34641.30717385588, 34728.0, 34850.47724284189, 25836.483270334866, 26023.869880650476, 26299.0, 26623.201303960912, 26881.731182687923, 25461.0, 25649.230523083592, 26013.479730405663, 26061.178811240985, 26538.469230615665, 28756.0, 28762.568561472013, 28806.0, 28841.7115828839, 29049.564256535745, 25639.743602563736, 25718.0, 25763.6135457972, 26470.0, 32401.0, 25204.74795252024, 27728.306792329546, 28274.71725282803, 28654.570181446878, 28805.135845924655], "value": 14462.483449154193, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.77299825598544, 407.14499550943026, 416.6604217562688, 422.6808759386662, 427.2160585325927, 11683.645190267769, 17220.655586888253, 17330.13349332533, 17978.0, 17992.820071799295, 12826.80759788604, 12867.613971580844, 14960.775588366183, 15061.849381506181, 17074.914625426885, 5509.669419834822, 5520.447955204488, 5616.466435688603, 5617.297837770278, 5648.0, 5513.0, 5583.0, 5591.888162236753, 5600.0, 5605.131204663284, 5384.300040994684, 5586.776528938858, 5609.635373700717, 5623.156526521014, 5630.831075067755, 5650.5479561635175, 5663.716814159276, 5680.261565996394, 5693.402192769782, 5709.571782116358, 4950.4307004694565, 5091.643584949055, 5143.537081662668, 5145.073886700392, 5145.434002259748, 22209.22267720622, 22301.107955681753, 22354.66468002981, 22395.44011399728, 22618.208362707228, 1548.9767653485208, 1552.0454920224095, 1569.6585793394672, 1576.282791329942, 1609.9195040247942, 13046.934765326208, 13084.345782710921, 13439.86560134396, 14401.567952961428, 14973.475928342456, 1685.0984723173167, 1689.9408520701716, 1720.1485264793882, 1733.0901276829638, 1739.9565010874703, 83376.0, 86719.13280867174, 89578.0, 90335.0, 91676.0, 10464.21518386124, 10501.737456563647, 10763.354198747944, 10792.352458852494, 10888.891111088946, 14816.3332650031, 14837.183954882585, 14895.329710163069, 14930.477433289783, 14945.103293802189, 6142.140100385949, 6746.730130794714, 7044.189918159374, 7191.604461754549, 7239.203687594466, 5625.662460252398, 5638.859028524238, 5751.769929202868, 5774.855628609234, 5816.156657284755, 6832.726690932307, 6865.5537390069485, 6866.553674011173, 7071.257517960643, 7072.3634872861685, 1955.491572191212, 1980.722698822166, 1999.0309545098066, 2010.0, 2120.67129594913, 830.7964548685611, 847.4745657692271, 855.5551113420986, 869.0440515432973, 882.6292956958093, 16448.917755411385, 16458.09480478585, 17056.403025894033, 17519.91240043817, 17535.824641753676, 855.4354126276628, 859.6604341285157, 861.1646702698498, 866.9479831210148, 869.452245085592, 9570.377925434825, 9571.521423928776, 9584.856227156599, 9682.467464289532, 9781.266405019653, 432825.0, 451042.0, 452679.0, 453517.0, 471955.0, 80653.59673201597, 88323.55838220767, 93188.0, 93419.53290233642, 94306.0, 1044.8328267477168, 1046.6493724602192, 1083.4691001409253, 1089.3028461784463, 1096.3915027160003, 15380.0, 15476.303566339768, 15868.206589670239, 16375.918120409322, 16595.83404165943, 42209.36685949775, 42307.576924230365, 42389.78805105955, 42725.57274427216, 43067.56932430761, 52894.0, 55259.723701381234, 56030.159547606, 56503.434965649816, 57210.42789572051, 7752.4573279869865, 7873.133955265031, 8104.351651867954, 8206.876896846672, 8277.875831862406, 11505.942470287595, 12226.327551984727, 12411.131220814455, 12499.56251531192, 13020.046992951133, 10334.741631459274, 10517.9474102629, 10592.735181620521, 10607.151427885596, 11004.339739615649, 8735.519546424754, 9271.443713377219, 9322.161005509537, 9593.952030240083, 9674.9032509674, 9809.362391444532, 9849.389911673556, 10097.343672661253, 10139.442330671884, 10149.188064954933, 7523.8495230093995, 7649.770506884803, 7719.614019299125, 7792.1428642848105, 7813.335866451188, 9417.62329506812, 9908.405495670282, 10277.845832312372, 10289.588416463257, 10739.624113156002], "value": 8626.85575834328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1260.6596219020864, 1305.3277562055544, 1306.9346532673367, 1312.0815429199563, 1326.3832317972142, 9284.535773211346, 9643.180329671986, 9645.855312170313, 9647.855282170756, 9654.324197306192, 9427.05729427057, 9577.808443831136, 9954.65158719445, 10020.0, 10031.548580313885, 2171.3594489625557, 2204.9228277010307, 2209.259897934186, 2211.447138215446, 2220.156340590573, 2066.2664754012335, 2170.967435488469, 2183.0285522942295, 2220.4448887778058, 2230.8438409311275, 1834.385480863912, 1892.933747318844, 1907.3515004898331, 1908.1794828223858, 1910.0927059646626, 2125.330520885916, 2196.286206982737, 2198.3075331270597, 2201.8458707890454, 2201.9889900550556, 2120.342693764938, 2144.3702785882683, 2156.5255643758346, 2171.250918433138, 2175.912963481459, 9804.215662746963, 9917.404955702681, 9929.950350248275, 9936.453495057769, 9940.701778946572, 5040.747962601892, 5205.24523944026, 5211.7133557654315, 5216.765245563921, 5240.161574148118, 3151.590293261884, 3190.7287880530184, 3264.379767844102, 3266.8856590019473, 3267.8725839585304, 1038.3199004651965, 1038.771470276538, 1047.1204188481636, 1051.4007016000883, 1060.1095080132725, 14956.326965286587, 14991.775123373158, 15068.0, 15114.697706045818, 15115.697686046327, 4465.598096171295, 4708.128996135726, 4729.43246810385, 4807.206810876166, 4829.44461386945, 10851.131909447384, 10885.0, 10914.072303854262, 10921.290116142583, 10953.83569246462, 4973.42805577356, 4977.552020318189, 4981.800727970913, 4985.0029994000615, 4992.950070499322, 4563.888444941686, 4569.314602809539, 4623.052274283764, 4625.1443482955765, 4651.046535460223, 3519.2081781598936, 3602.081469225383, 3637.490751294768, 3698.204885949534, 3725.590185079639, 1083.978320433587, 1097.6158344579333, 1105.8064838653365, 1110.245033377295, 1114.637742733615, 601.437655791836, 644.432899048834, 662.0995446193174, 673.8079647300594, 685.7771224352106, 5289.127293996447, 5327.413984461706, 5339.919901201485, 5387.488188622075, 5642.294713160855, 812.34200297759, 813.8209593889335, 829.4484168028314, 839.0895877972346, 874.6763697431991, 3024.9395012099635, 3206.951895721566, 3758.2671379080816, 3854.8265328060306, 3962.167944731581, 12485.750284994248, 12979.41592628334, 12996.155249908727, 13016.674583135497, 13054.934725326502, 13093.672658183621, 13180.209187448783, 13206.141600795918, 13319.667008324675, 13360.465581376831, 9140.54297285133, 9418.058194180665, 9423.670171543963, 9425.717228483156, 9427.528623568795, 5704.286964129484, 5821.359650438533, 5847.239858818326, 5905.648924843521, 6018.428249316308, 11979.802019797951, 12029.097817663886, 12090.516379344728, 12283.815742763687, 12295.75408491843, 11872.821907671565, 12403.62789116328, 12452.937735311265, 12480.438380272728, 12488.687782805502, 8306.0, 8623.698170564001, 8631.654733810828, 8632.741017769476, 8634.222919937236, 8780.209781119729, 8862.113788621085, 8909.331800114887, 8969.071649595367, 8998.59506322204, 7522.097348318237, 7565.508241964255, 7588.582627955516, 7626.237376262329, 7844.0, 5643.604947653789, 7233.095863017123, 7258.201597824341, 7512.39900807945, 7530.811729706801, 6636.336366363325, 6687.565308254948, 6698.39714425704, 6702.061711360511, 6710.530262881552, 4619.191641462798, 4677.438707355139, 4719.905601888012, 4758.31004504338, 4846.660733748745, 4497.617702495194, 4616.8614941551805, 4674.462436819673, 4740.478547359857, 4906.607471402351], "value": 4570.80249010223, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.6470916445807, 394.0857211269854, 400.21158318113316, 401.71478250442186, 403.4573498644322, 5310.123829568117, 5384.219288203209, 5406.729663516819, 5412.161115027167, 5417.074779619011, 5033.54698077173, 5193.584513238941, 5227.320448341719, 5258.0, 5298.523132918037, 4095.7133000689964, 4127.62851343379, 4151.335786274197, 4156.667466602679, 4158.604932531404, 4145.543990161095, 4146.543880173179, 4158.792060396968, 4159.376093585971, 4163.958360416387, 3993.7204395692315, 4060.9593904061026, 4125.989132662502, 4128.752274863503, 4137.937930931039, 4081.163361510883, 4095.0515628747403, 4095.9795201024103, 4098.581836716322, 4112.506499220098, 3583.94624080639, 3596.4605309203566, 3613.692836108934, 3622.2031153146268, 3625.419932810764, 4996.200607902754, 5167.509086636764, 5168.8966220675375, 5173.793048278064, 5203.0, 4717.433907931036, 4727.463574338354, 4736.905261894743, 4752.904941901177, 4752.928706069377, 5540.944590554083, 5569.777208911639, 5594.2447769551, 5601.6358936668985, 5604.439556044363, 3966.206758648253, 3969.7816620085882, 3974.880753577397, 3975.463312452811, 4003.5195776506716, 5608.298962629671, 5658.207850900877, 5708.743106560215, 5761.769529218826, 5945.167676525247, 4014.0767623446186, 4045.4134150548593, 4051.2505186540584, 4052.209819085309, 4060.939085913714, 4136.503619565611, 4144.088300573929, 4144.48193975753, 4145.253854306194, 4146.217126635515, 4449.933251001237, 4452.0, 4510.571495707903, 4686.297055441642, 4721.409823772028, 5589.664620122806, 5593.496585307342, 5600.915986260209, 5618.101103823368, 5621.128725047624, 1410.4428750643438, 1419.7870319451958, 1425.2232533269394, 1427.8072460217945, 1428.1502506008908, 926.5645146781002, 965.8309795785709, 977.4917043137528, 979.7501637082498, 988.7725823060592, 4914.680545764514, 4919.450373132452, 4921.335619691367, 4933.0, 4943.184374578154, 5814.273215848019, 5814.447627475383, 5827.621204621686, 5836.620619659709, 5839.678817664984, 4710.929336059962, 4861.416630004422, 4879.56083952446, 4898.485659005824, 4905.337779399735, 3045.771567132474, 3050.6491753448195, 3069.5395690646137, 3089.5211242257487, 3094.9535756963664, 5373.7581808818695, 5383.246345511631, 5413.648112872651, 5422.213779002022, 5426.480585436084, 5668.858278543069, 5886.23478947734, 5899.410058994152, 5910.615809972338, 5921.555883308769, 5350.464953504618, 5379.435159308295, 5388.784448622089, 5426.457354264463, 5438.564914806727, 5530.446955304598, 5755.71221438918, 5793.884122317446, 5844.516080665615, 5861.794837180678, 5572.944270557243, 5595.804146854841, 5612.803551875665, 5678.4605462480995, 5682.971585142048, 5127.128388173945, 5278.736063196901, 5296.781740199699, 5304.010995876546, 5328.60035497347, 3739.6447337502896, 3881.4177873319227, 3885.7085718570656, 4076.1643863008603, 4110.630043296118, 5817.331006934257, 5841.620294680833, 5852.94147058524, 5862.618929769551, 5866.911996319973, 5462.508374246337, 5625.943740562542, 5662.915056274242, 5672.347680016851, 5682.499700131929, 5621.831345059655, 5623.915641265467, 5634.211210430461, 5651.6043876928225, 5667.234923285304, 5340.225667278145, 5519.2824932758485, 5560.304961879765, 5565.831175453201, 5572.136318870582, 5590.720463976703, 5634.6337488063145, 5644.887102258014, 5653.802116925888, 5659.122835960433, 5521.723913804213, 5529.419410961791, 5530.778768849201, 5531.585131115102, 5537.944620553743], "value": 4221.670699185196, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.9273840769903, 581.3314688108676, 585.0843430032003, 591.8875413671402, 592.5082181789113, 10512.947435262831, 11096.722581935446, 11107.72230694233, 11299.322040677565, 12079.698007549816, 11745.530178792838, 11869.7626047479, 11900.0, 11992.520299188021, 12013.939930300336, 829.2122483640535, 834.6911642692212, 835.1231207232412, 835.7075023741703, 835.9373047021468, 812.6261919517029, 840.260570697785, 848.2874385516166, 848.5205858689851, 849.133883438895, 888.5779254853925, 890.132121181849, 890.2655309369763, 890.5725251879102, 890.6971629645919, 881.0220655072864, 882.4176043811054, 883.6465413834453, 885.008790155024, 886.0253720907015, 654.8461111638761, 657.4773055420952, 657.8585604095142, 657.8881590129641, 659.5943494750742, 12072.154949153562, 12523.499060037588, 12524.624261272176, 12642.431090600852, 12658.050646201571, 12359.32023738694, 12394.442250098768, 12447.502099915993, 12480.375981200994, 12660.240385576895, 9122.954385228099, 9139.543022848831, 9299.674511392137, 9328.580213890391, 9363.531823408803, 2544.224011676438, 2572.935676608081, 2611.595202743578, 2633.749793769608, 2683.087750164934, 64949.0, 65400.672996635185, 65525.0, 66853.33146668569, 66964.0, 8910.599023043978, 8931.240844528158, 9089.590968406437, 9707.9029209707, 9844.310898237054, 17851.73222401665, 18208.45374638763, 18217.18022688982, 18245.817541824414, 18248.45254642363, 6192.783252586228, 6273.0904018917, 6296.874062518816, 6519.0, 6586.1438013058, 3146.24490122369, 3220.4686226772787, 3254.0, 3298.86804527821, 3413.992872102736, 3259.1200375898375, 3651.835667394974, 3802.049487628093, 3839.155385815116, 3845.6346647068485, 720.330093013492, 724.9637518124073, 727.4253339861556, 753.9509931854412, 756.5347311403448, 475.0, 475.9809607615726, 479.7313504437526, 483.15447966059395, 486.79068000759304, 7954.032342097871, 8187.0, 8241.711540096068, 8413.681819992775, 8507.191816777397, 458.98164073436686, 474.962003039756, 476.41401076675885, 483.13518801345197, 488.47595502033175, 3702.162237152429, 3756.1173124315765, 3843.2889915365745, 3906.3945088511327, 3907.843686252518, 61847.0, 63028.0, 63637.0, 65659.0, 65923.0, 41428.58571414247, 44478.55521444809, 55172.72413637987, 56312.0, 56340.71829640825, 46082.76958615185, 46302.305465418045, 47043.29435058546, 47781.283280751515, 48816.51183488191, 7354.264573542603, 7622.885656715042, 7785.532868027936, 7845.3723702104835, 7899.723509677134, 27289.86355068212, 27295.044673436336, 27747.58378624282, 27965.30086747766, 28332.858335708188, 26668.59997100006, 32490.51264230991, 32598.34803303968, 32856.178595535304, 33049.669503304656, 9488.0, 9514.809703806024, 9526.714198574055, 9561.856572151562, 9564.760880977752, 9713.258801803122, 9842.261830362895, 9986.051325124101, 10181.592736290466, 10318.226133039901, 9152.0, 9379.812403752023, 9416.0, 9798.0, 9874.40753554789, 9699.6120155193, 9914.355566888129, 9975.750606234611, 10040.0, 10430.217733669853, 9898.158656514277, 9926.454045027593, 9959.502024898871, 9976.900230997597, 10155.441450720282, 7093.929060709327, 7212.2427145151105, 7355.1541572719825, 7373.0, 7382.704691812267, 7616.543007419573, 7719.807004824925, 7828.373730101692, 7979.48133371329, 8123.47197432165], "value": 5775.919850658187, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 110.0, 110.0, 111.0, 112.0, 111.0, 110.0, 111.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 123.0, 122.0, 177.0, 126.0, 122.0, 128.0, 122.0, 151.0, 122.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 162.0, 166.0, 164.0, 163.0, 162.0, 163.0, 161.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 111.0, 75.0, 74.0, 77.0, 75.0, 93.0, 74.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 112.0, 84.0, 83.0, 84.0, 112.0, 84.0, 83.0, 108.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 149.0, 149.0, 148.0, 149.0, 148.0, 148.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 93.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 62.0, 62.0, 62.0, 64.0, 65.0, 64.0, 63.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 84.0, 83.0, 82.0, 91.0, 89.0, 81.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 143.0, 146.0, 131.0, 133.0, 148.0, 131.0, 230.0, 134.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 195.0, 197.0, 199.0, 201.0, 205.0, 199.0, 197.0, 194.0, 195.0], "value": 197.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 70.0, 72.0, 73.0, 73.0, 71.0, 72.0, 71.0, 74.0], "value": 71.9, "unit": "score"}]}]} 19:28:39 INFO - Return code: 0 19:28:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:28:39 ERROR - # TBPL SUCCESS # 19:28:39 INFO - Running post-action listener: _resource_record_post_action 19:28:39 INFO - [mozharness: 2017-03-24 02:28:39.795346Z] Finished run-tests step (success) 19:28:39 INFO - Running post-run listener: _resource_record_post_run 19:28:39 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 353894400; Read time: 24; Write time: 306172 19:28:39 INFO - TinderboxPrint: CPU usage
13.0% 19:28:39 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 19:28:39 INFO - TinderboxPrint: I/O write bytes / time
353,894,400 / 306,172 19:28:39 INFO - TinderboxPrint: CPU idle
8,362.9 (87.0%) 19:28:39 INFO - TinderboxPrint: CPU user
1,200.6 (12.5%) 19:28:39 INFO - TinderboxPrint: Swap in / out
0 / 0 19:28:39 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106463232; Read time: 0; Write time: 129792 19:28:39 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 247431168; Read time: 24; Write time: 176380 19:28:40 INFO - Running post-run listener: _upload_blobber_files 19:28:40 INFO - Blob upload gear active. 19:28:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:28:40 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 19:28:40 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:28:40 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:28:40 INFO - (blobuploader) - INFO - Open directory for files ... 19:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:44 INFO - (blobuploader) - INFO - Done attempting. 19:28:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:28:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:28:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:45 INFO - (blobuploader) - INFO - Done attempting. 19:28:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:28:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:28:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:46 INFO - (blobuploader) - INFO - Done attempting. 19:28:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:28:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:28:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:46 INFO - (blobuploader) - INFO - Done attempting. 19:28:46 INFO - (blobuploader) - INFO - Iteration through files over. 19:28:46 INFO - Return code: 0 19:28:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:28:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:28:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0621d358f0cadf126f42dff291be03c3e43217260f9ffb4d9930fde209060a81df2b3c485c6d1e5a8bba262860f667ec5aedc4708614d51565814ab5b937be6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c9928b6969b32052199998c8f3c26935785db608d1dc744a67396a46c129634ab6b1c30962d08a9b33f0a7b84a4c19663d31189917a654db4b80630dfd0e49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/eea728070d87afb535edc339cba884238d5707f927fb19e57ef98ae84d71db735d46775061d7dc0b6363db41231dbca58ad429dbdf1c98acbc240b66bbb7d8c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c9d744ae495d09dc582ce971a7a11d08a816c8bb949d23048441878ffcbbd1196aa1e48e908d9862dbd211141f2e032a1446adbe62ee1d744b72673bc380eccb"} 19:28:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:28:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:28:46 INFO - Contents: 19:28:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0621d358f0cadf126f42dff291be03c3e43217260f9ffb4d9930fde209060a81df2b3c485c6d1e5a8bba262860f667ec5aedc4708614d51565814ab5b937be6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c9928b6969b32052199998c8f3c26935785db608d1dc744a67396a46c129634ab6b1c30962d08a9b33f0a7b84a4c19663d31189917a654db4b80630dfd0e49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/eea728070d87afb535edc339cba884238d5707f927fb19e57ef98ae84d71db735d46775061d7dc0b6363db41231dbca58ad429dbdf1c98acbc240b66bbb7d8c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c9d744ae495d09dc582ce971a7a11d08a816c8bb949d23048441878ffcbbd1196aa1e48e908d9862dbd211141f2e032a1446adbe62ee1d744b72673bc380eccb"} 19:28:46 INFO - Running post-run listener: copy_logs_to_upload_dir 19:28:46 INFO - Copying logs to upload dir... 19:28:46 INFO - mkdir: /builds/slave/test/build/upload/logs 19:28:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.019463 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-03-23 19:28:46.752441) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 19:28:46.756470) ========= 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=dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0621d358f0cadf126f42dff291be03c3e43217260f9ffb4d9930fde209060a81df2b3c485c6d1e5a8bba262860f667ec5aedc4708614d51565814ab5b937be6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c9928b6969b32052199998c8f3c26935785db608d1dc744a67396a46c129634ab6b1c30962d08a9b33f0a7b84a4c19663d31189917a654db4b80630dfd0e49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/eea728070d87afb535edc339cba884238d5707f927fb19e57ef98ae84d71db735d46775061d7dc0b6363db41231dbca58ad429dbdf1c98acbc240b66bbb7d8c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c9d744ae495d09dc582ce971a7a11d08a816c8bb949d23048441878ffcbbd1196aa1e48e908d9862dbd211141f2e032a1446adbe62ee1d744b72673bc380eccb"} build_url:https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011123 build_url: 'https://queue.taskcluster.net/v1/task/En4_ybPfRAirG61usP7e7w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0621d358f0cadf126f42dff291be03c3e43217260f9ffb4d9930fde209060a81df2b3c485c6d1e5a8bba262860f667ec5aedc4708614d51565814ab5b937be6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/34c9928b6969b32052199998c8f3c26935785db608d1dc744a67396a46c129634ab6b1c30962d08a9b33f0a7b84a4c19663d31189917a654db4b80630dfd0e49", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/eea728070d87afb535edc339cba884238d5707f927fb19e57ef98ae84d71db735d46775061d7dc0b6363db41231dbca58ad429dbdf1c98acbc240b66bbb7d8c7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c9d744ae495d09dc582ce971a7a11d08a816c8bb949d23048441878ffcbbd1196aa1e48e908d9862dbd211141f2e032a1446adbe62ee1d744b72673bc380eccb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 19:28:46.780940) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 19:28:46.781245) ========= 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=dac658600622fd96716c9acf000001b3-1490321262.294871-1179512523 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 19:28:46.864893) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 19:28:46.865257) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 19:28:46.865655) ========= ========= Total master_lag: 0.21 =========